builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0098 starttime: 1472611435.62 results: success (0) buildid: 20160830173521 builduid: 1d6ba466ed6741d0a73769d39b2d1275 revision: 5931a8286060ca165423aba08b165e0c8bff71d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.618887) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.619278) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.619577) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.642413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.642690) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U17ndrUzbr/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-30 19:43:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.01M=0.001s 2016-08-30 19:43:55 (8.01 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118609 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.783138) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.783447) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U17ndrUzbr/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020298 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.835116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:55.835970) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5931a8286060ca165423aba08b165e0c8bff71d9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5931a8286060ca165423aba08b165e0c8bff71d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U17ndrUzbr/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-30 19:43:55,911 truncating revision to first 12 chars 2016-08-30 19:43:55,911 Setting DEBUG logging. 2016-08-30 19:43:55,912 attempt 1/10 2016-08-30 19:43:55,912 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5931a8286060?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 19:43:56,498 unpacking tar archive at: mozilla-central-5931a8286060/testing/mozharness/ program finished with exit code 0 elapsedTime=0.842915 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:56.704051) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:56.704453) ========= script_repo_revision: 5931a8286060ca165423aba08b165e0c8bff71d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:56.704923) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:56.705248) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 19:43:56.724895) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-30 19:43:56.725217) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U17ndrUzbr/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:43:56 INFO - MultiFileLogger online at 20160830 19:43:56 in /builds/slave/test 19:43:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:43:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:43:56 INFO - {'append_to_log': False, 19:43:56 INFO - 'base_work_dir': '/builds/slave/test', 19:43:56 INFO - 'blob_upload_branch': 'Firefox', 19:43:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:43:56 INFO - 'branch': 'Firefox', 19:43:56 INFO - 'buildbot_json_path': 'buildprops.json', 19:43:56 INFO - 'config_files': ('talos/mac_config.py',), 19:43:56 INFO - 'default_actions': ('clobber', 19:43:56 INFO - 'read-buildbot-config', 19:43:56 INFO - 'download-and-extract', 19:43:56 INFO - 'populate-webroot', 19:43:56 INFO - 'create-virtualenv', 19:43:56 INFO - 'install', 19:43:56 INFO - 'run-tests'), 19:43:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:43:56 INFO - 'download_minidump_stackwalk': True, 19:43:56 INFO - 'download_symbols': 'ondemand', 19:43:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:43:56 INFO - 'tooltool.py': '/tools/tooltool.py', 19:43:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:43:56 INFO - '/tools/misc-python/virtualenv.py')}, 19:43:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:43:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:43:56 INFO - 'installer_path': 'installer.exe', 19:43:56 INFO - 'log_level': 'info', 19:43:56 INFO - 'log_name': 'talos', 19:43:56 INFO - 'log_to_console': True, 19:43:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:43:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:43:56 INFO - 'opt_config_files': (), 19:43:56 INFO - 'pip_index': False, 19:43:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:43:56 INFO - 'cmd': ('bash', 19:43:56 INFO - '-c', 19:43:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:43:56 INFO - 'enabled': True, 19:43:56 INFO - 'halt_on_failure': False, 19:43:56 INFO - 'name': 'check_screen_resolution'},), 19:43:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:43:56 INFO - 'cmd': ('bash', 19:43:56 INFO - '-c', 19:43:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:43:56 INFO - 'enabled': True, 19:43:56 INFO - 'halt_on_failure': False, 19:43:56 INFO - 'name': 'check_screen_resolution'},), 19:43:56 INFO - 'run_cmd_checks_enabled': True, 19:43:56 INFO - 'sps_profile': False, 19:43:56 INFO - 'sps_profile_interval': 0, 19:43:56 INFO - 'suite': 'dromaeojs', 19:43:56 INFO - 'system_bits': '32', 19:43:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:43:56 INFO - 'title': 't-yosemite-r7-0098', 19:43:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:43:56 INFO - 'use_talos_json': True, 19:43:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:43:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:43:56 INFO - 'work_dir': 'build'} 19:43:56 INFO - [mozharness: 2016-08-31 02:43:56.916645Z] Running clobber step. 19:43:56 INFO - Running pre-action listener: _resource_record_pre_action 19:43:56 INFO - Running main action method: clobber 19:43:56 INFO - rmtree: /builds/slave/test/build 19:43:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:43:59 INFO - Running post-action listener: _resource_record_post_action 19:43:59 INFO - [mozharness: 2016-08-31 02:43:59.101221Z] Finished clobber step (success) 19:43:59 INFO - [mozharness: 2016-08-31 02:43:59.101422Z] Running read-buildbot-config step. 19:43:59 INFO - Running pre-action listener: _resource_record_pre_action 19:43:59 INFO - Running main action method: read_buildbot_config 19:43:59 INFO - Using buildbot properties: 19:43:59 INFO - { 19:43:59 INFO - "project": "", 19:43:59 INFO - "product": "firefox", 19:43:59 INFO - "script_repo_revision": "production", 19:43:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:43:59 INFO - "repository": "", 19:43:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:43:59 INFO - "buildid": "20160830173521", 19:43:59 INFO - "pgo_build": "False", 19:43:59 INFO - "basedir": "/builds/slave/test", 19:43:59 INFO - "buildnumber": 8, 19:43:59 INFO - "slavename": "t-yosemite-r7-0098", 19:43:59 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:43:59 INFO - "platform": "yosemite_r7", 19:43:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:43:59 INFO - "branch": "mozilla-central", 19:43:59 INFO - "repo_path": "mozilla-central", 19:43:59 INFO - "revision": "5931a8286060ca165423aba08b165e0c8bff71d9", 19:43:59 INFO - "stage_platform": "macosx64", 19:43:59 INFO - "builduid": "1d6ba466ed6741d0a73769d39b2d1275", 19:43:59 INFO - "slavebuilddir": "test" 19:43:59 INFO - } 19:43:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:43:59 INFO - Running post-action listener: _resource_record_post_action 19:43:59 INFO - [mozharness: 2016-08-31 02:43:59.106883Z] Finished read-buildbot-config step (success) 19:43:59 INFO - [mozharness: 2016-08-31 02:43:59.107043Z] Running download-and-extract step. 19:43:59 INFO - Running pre-action listener: _resource_record_pre_action 19:43:59 INFO - Running main action method: download_and_extract 19:43:59 INFO - mkdir: /builds/slave/test/build/tests 19:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:43:59 INFO - trying https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:43:59 INFO - Downloading https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:43:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 19:44:00 INFO - Downloaded 1199 bytes. 19:44:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:44:00 INFO - Using the following test package requirements: 19:44:00 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:44:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:44:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:44:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'jsshell-mac.zip'], 19:44:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:44:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:44:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:44:00 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:44:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:44:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:44:00 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:44:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:00 INFO - trying https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:44:00 INFO - Downloading https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:44:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:44:01 INFO - Downloaded 16390593 bytes. 19:44:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:44:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:44:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:08 INFO - trying https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:44:08 INFO - Downloading https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:44:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:44:09 INFO - Downloaded 16390593 bytes. 19:44:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:44:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:15 INFO - trying https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:44:15 INFO - Downloading https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:44:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:44:16 INFO - Downloaded 14413998 bytes. 19:44:16 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:17 INFO - trying https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:44:17 INFO - Downloading https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:44:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 19:44:20 INFO - Downloaded 111562567 bytes. 19:44:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:44:20 INFO - mkdir: /builds/slave/test/properties 19:44:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:44:20 INFO - Writing to file /builds/slave/test/properties/build_url 19:44:20 INFO - Contents: 19:44:20 INFO - build_url:https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:44:20 INFO - Running post-action listener: _resource_record_post_action 19:44:20 INFO - Running post-action listener: set_extra_try_arguments 19:44:20 INFO - [mozharness: 2016-08-31 02:44:20.645384Z] Finished download-and-extract step (success) 19:44:20 INFO - [mozharness: 2016-08-31 02:44:20.645506Z] Running populate-webroot step. 19:44:20 INFO - Running pre-action listener: _resource_record_pre_action 19:44:20 INFO - Running main action method: populate_webroot 19:44:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:44:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:44:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:44:20 INFO - '--tppagecycles', 19:44:20 INFO - '7'], 19:44:20 INFO - 'tests': ['tsvgm']}}, 19:44:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:44:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:44:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:44:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:44:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:44:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:44:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['damp', 'tps']}, 19:44:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'tests': ['damp', 'tps']}, 19:44:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['dromaeo_dom']}, 19:44:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:44:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['basic_compositor_video']}, 19:44:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:44:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['a11yr', 19:44:20 INFO - 'ts_paint', 19:44:20 INFO - 'tpaint', 19:44:20 INFO - 'sessionrestore', 19:44:20 INFO - 'sessionrestore_no_auto_restore', 19:44:20 INFO - 'tabpaint']}, 19:44:20 INFO - 'other-e10s': {'tests': ['a11yr', 19:44:20 INFO - 'ts_paint', 19:44:20 INFO - 'tpaint', 19:44:20 INFO - 'sessionrestore', 19:44:20 INFO - 'sessionrestore_no_auto_restore', 19:44:20 INFO - 'tabpaint']}, 19:44:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:44:20 INFO - 'ts_paint', 19:44:20 INFO - 'tpaint', 19:44:20 INFO - 'sessionrestore', 19:44:20 INFO - 'sessionrestore_no_auto_restore', 19:44:20 INFO - 'tabpaint']}, 19:44:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:44:20 INFO - 'ts_paint', 19:44:20 INFO - 'tpaint', 19:44:20 INFO - 'sessionrestore', 19:44:20 INFO - 'sessionrestore_no_auto_restore', 19:44:20 INFO - 'tabpaint']}, 19:44:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['a11yr', 19:44:20 INFO - 'ts_paint', 19:44:20 INFO - 'tpaint', 19:44:20 INFO - 'sessionrestore', 19:44:20 INFO - 'sessionrestore_no_auto_restore', 19:44:20 INFO - 'tabpaint']}, 19:44:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['a11yr', 19:44:20 INFO - 'ts_paint', 19:44:20 INFO - 'tpaint', 19:44:20 INFO - 'sessionrestore', 19:44:20 INFO - 'sessionrestore_no_auto_restore', 19:44:20 INFO - 'tabpaint']}, 19:44:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['tsvgx', 19:44:20 INFO - 'tsvgr_opacity', 19:44:20 INFO - 'tart', 19:44:20 INFO - 'tscrollx', 19:44:20 INFO - 'cart']}, 19:44:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:44:20 INFO - 'tsvgr_opacity', 19:44:20 INFO - 'tart', 19:44:20 INFO - 'tscrollx', 19:44:20 INFO - 'cart']}, 19:44:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'talos_options': ['--disable-e10s'], 19:44:20 INFO - 'tests': ['tp5o']}, 19:44:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'tests': ['tp5o']}, 19:44:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'talos_options': ['--disable-e10s', 19:44:20 INFO - '--xperf_path', 19:44:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:44:20 INFO - 'tests': ['tp5n']}, 19:44:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:44:20 INFO - 'talos_options': ['--xperf_path', 19:44:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:44:20 INFO - 'tests': ['tp5n']}}, 19:44:20 INFO - 'talos.zip': {'path': '', 19:44:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:44:20 INFO - Running post-action listener: _resource_record_post_action 19:44:20 INFO - [mozharness: 2016-08-31 02:44:20.665559Z] Finished populate-webroot step (success) 19:44:20 INFO - [mozharness: 2016-08-31 02:44:20.665664Z] Running create-virtualenv step. 19:44:20 INFO - Running pre-action listener: _resource_record_pre_action 19:44:20 INFO - Running main action method: create_virtualenv 19:44:20 INFO - Creating virtualenv /builds/slave/test/build/venv 19:44:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:44:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:44:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:44:20 INFO - Using real prefix '/tools/python27' 19:44:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:44:21 INFO - Installing distribute.............................................................................................................................................................................................done. 19:44:24 INFO - Installing pip.................done. 19:44:24 INFO - Return code: 0 19:44:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:24 INFO - 'HOME': '/Users/cltbld', 19:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:24 INFO - 'LOGNAME': 'cltbld', 19:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:24 INFO - 'MOZ_NO_REMOTE': '1', 19:44:24 INFO - 'NO_EM_RESTART': '1', 19:44:24 INFO - 'PAGER': '/bin/cat', 19:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:24 INFO - 'PWD': '/builds/slave/test', 19:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:24 INFO - 'SHELL': '/bin/bash', 19:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:24 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:24 INFO - 'USER': 'cltbld', 19:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:24 INFO - 'XPC_FLAGS': '0x0', 19:44:24 INFO - 'XPC_SERVICE_NAME': '0', 19:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:44:24 INFO - Cleaning up... 19:44:24 INFO - Return code: 0 19:44:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:24 INFO - 'HOME': '/Users/cltbld', 19:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:24 INFO - 'LOGNAME': 'cltbld', 19:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:24 INFO - 'MOZ_NO_REMOTE': '1', 19:44:24 INFO - 'NO_EM_RESTART': '1', 19:44:24 INFO - 'PAGER': '/bin/cat', 19:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:24 INFO - 'PWD': '/builds/slave/test', 19:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:24 INFO - 'SHELL': '/bin/bash', 19:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:24 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:24 INFO - 'USER': 'cltbld', 19:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:24 INFO - 'XPC_FLAGS': '0x0', 19:44:24 INFO - 'XPC_SERVICE_NAME': '0', 19:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:25 INFO - Downloading/unpacking psutil>=3.1.1 19:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:44:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:44:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:44:28 INFO - Installing collected packages: psutil 19:44:28 INFO - Running setup.py install for psutil 19:44:28 INFO - building 'psutil._psutil_osx' extension 19:44:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:44:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:44:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:44:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:44:28 INFO - building 'psutil._psutil_posix' extension 19:44:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:44:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:44:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:44:28 INFO - ^ 19:44:28 INFO - 1 warning generated. 19:44:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:44:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:44:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:44:28 INFO - Successfully installed psutil 19:44:28 INFO - Cleaning up... 19:44:29 INFO - Return code: 0 19:44:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:44:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:29 INFO - 'HOME': '/Users/cltbld', 19:44:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:29 INFO - 'LOGNAME': 'cltbld', 19:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:29 INFO - 'MOZ_NO_REMOTE': '1', 19:44:29 INFO - 'NO_EM_RESTART': '1', 19:44:29 INFO - 'PAGER': '/bin/cat', 19:44:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:29 INFO - 'PWD': '/builds/slave/test', 19:44:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:29 INFO - 'SHELL': '/bin/bash', 19:44:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:29 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:29 INFO - 'USER': 'cltbld', 19:44:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:29 INFO - 'XPC_FLAGS': '0x0', 19:44:29 INFO - 'XPC_SERVICE_NAME': '0', 19:44:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:44:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:44:31 INFO - Installing collected packages: mozsystemmonitor 19:44:31 INFO - Running setup.py install for mozsystemmonitor 19:44:31 INFO - Successfully installed mozsystemmonitor 19:44:31 INFO - Cleaning up... 19:44:31 INFO - Return code: 0 19:44:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:44:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:31 INFO - 'HOME': '/Users/cltbld', 19:44:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:31 INFO - 'LOGNAME': 'cltbld', 19:44:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:31 INFO - 'MOZ_NO_REMOTE': '1', 19:44:31 INFO - 'NO_EM_RESTART': '1', 19:44:31 INFO - 'PAGER': '/bin/cat', 19:44:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:31 INFO - 'PWD': '/builds/slave/test', 19:44:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:31 INFO - 'SHELL': '/bin/bash', 19:44:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:31 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:31 INFO - 'USER': 'cltbld', 19:44:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:31 INFO - 'XPC_FLAGS': '0x0', 19:44:31 INFO - 'XPC_SERVICE_NAME': '0', 19:44:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:32 INFO - Downloading/unpacking jsonschema==2.5.1 19:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:44:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:35 INFO - Downloading functools32-3.2.3-2.tar.gz 19:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:44:35 INFO - warning: no files found matching '*.txt' 19:44:35 INFO - no previously-included directories found matching 'build' 19:44:35 INFO - no previously-included directories found matching 'dist' 19:44:35 INFO - no previously-included directories found matching '.git*' 19:44:35 INFO - Installing collected packages: jsonschema, functools32 19:44:35 INFO - Running setup.py install for functools32 19:44:35 INFO - warning: no files found matching '*.txt' 19:44:35 INFO - no previously-included directories found matching 'build' 19:44:35 INFO - no previously-included directories found matching 'dist' 19:44:35 INFO - no previously-included directories found matching '.git*' 19:44:35 INFO - Successfully installed jsonschema functools32 19:44:35 INFO - Cleaning up... 19:44:35 INFO - Return code: 0 19:44:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:35 INFO - 'HOME': '/Users/cltbld', 19:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:35 INFO - 'LOGNAME': 'cltbld', 19:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:35 INFO - 'MOZ_NO_REMOTE': '1', 19:44:35 INFO - 'NO_EM_RESTART': '1', 19:44:35 INFO - 'PAGER': '/bin/cat', 19:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:35 INFO - 'PWD': '/builds/slave/test', 19:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:35 INFO - 'SHELL': '/bin/bash', 19:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:35 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:35 INFO - 'USER': 'cltbld', 19:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:35 INFO - 'XPC_FLAGS': '0x0', 19:44:35 INFO - 'XPC_SERVICE_NAME': '0', 19:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:44:35 INFO - Cleaning up... 19:44:35 INFO - Return code: 0 19:44:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:44:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:44:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:35 INFO - 'HOME': '/Users/cltbld', 19:44:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:35 INFO - 'LOGNAME': 'cltbld', 19:44:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:35 INFO - 'MOZ_NO_REMOTE': '1', 19:44:35 INFO - 'NO_EM_RESTART': '1', 19:44:35 INFO - 'PAGER': '/bin/cat', 19:44:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:35 INFO - 'PWD': '/builds/slave/test', 19:44:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:35 INFO - 'SHELL': '/bin/bash', 19:44:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:35 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:35 INFO - 'USER': 'cltbld', 19:44:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:35 INFO - 'XPC_FLAGS': '0x0', 19:44:35 INFO - 'XPC_SERVICE_NAME': '0', 19:44:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:35 INFO - Downloading/unpacking blobuploader==1.2.4 19:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:38 INFO - Downloading blobuploader-1.2.4.tar.gz 19:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:44:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:44:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:39 INFO - Downloading docopt-0.6.1.tar.gz 19:44:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:44:39 INFO - Installing collected packages: blobuploader, requests, docopt 19:44:39 INFO - Running setup.py install for blobuploader 19:44:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:44:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:44:39 INFO - Running setup.py install for requests 19:44:40 INFO - Running setup.py install for docopt 19:44:40 INFO - Successfully installed blobuploader requests docopt 19:44:40 INFO - Cleaning up... 19:44:40 INFO - Return code: 0 19:44:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:40 INFO - 'HOME': '/Users/cltbld', 19:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:40 INFO - 'LOGNAME': 'cltbld', 19:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:40 INFO - 'MOZ_NO_REMOTE': '1', 19:44:40 INFO - 'NO_EM_RESTART': '1', 19:44:40 INFO - 'PAGER': '/bin/cat', 19:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:40 INFO - 'PWD': '/builds/slave/test', 19:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:40 INFO - 'SHELL': '/bin/bash', 19:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:40 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:40 INFO - 'USER': 'cltbld', 19:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:40 INFO - 'XPC_FLAGS': '0x0', 19:44:40 INFO - 'XPC_SERVICE_NAME': '0', 19:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:44:40 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-pAjdPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:44:40 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-riXBwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-2hXX0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-mLhntP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-XnCROl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-b0XaB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-MMFwYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9Yk0lQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-7mCVOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-PGyWcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Sny_JD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:44:41 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-sZyRLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:44:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-uGT7fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:44:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Dxsd6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:44:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-TxBoH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:44:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-qAygps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:44:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:44:42 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-y6AZOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:44:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:44:42 INFO - Running setup.py install for manifestparser 19:44:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:44:42 INFO - Running setup.py install for mozcrash 19:44:42 INFO - Running setup.py install for mozdebug 19:44:43 INFO - Running setup.py install for mozdevice 19:44:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Running setup.py install for mozfile 19:44:43 INFO - Running setup.py install for mozhttpd 19:44:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Running setup.py install for mozinfo 19:44:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Running setup.py install for mozInstall 19:44:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:44:43 INFO - Running setup.py install for mozleak 19:44:43 INFO - Running setup.py install for mozlog 19:44:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:44:44 INFO - Running setup.py install for moznetwork 19:44:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:44:44 INFO - Running setup.py install for mozprocess 19:44:44 INFO - Running setup.py install for mozprofile 19:44:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:44:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:44:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:44:44 INFO - Running setup.py install for mozrunner 19:44:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:44:44 INFO - Running setup.py install for mozscreenshot 19:44:44 INFO - Running setup.py install for moztest 19:44:45 INFO - Running setup.py install for mozversion 19:44:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:44:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:44:45 INFO - Cleaning up... 19:44:45 INFO - Return code: 0 19:44:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:44:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:44:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:45 INFO - 'HOME': '/Users/cltbld', 19:44:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:45 INFO - 'LOGNAME': 'cltbld', 19:44:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:45 INFO - 'MOZ_NO_REMOTE': '1', 19:44:45 INFO - 'NO_EM_RESTART': '1', 19:44:45 INFO - 'PAGER': '/bin/cat', 19:44:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:45 INFO - 'PWD': '/builds/slave/test', 19:44:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:45 INFO - 'SHELL': '/bin/bash', 19:44:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:45 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:45 INFO - 'USER': 'cltbld', 19:44:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:45 INFO - 'XPC_FLAGS': '0x0', 19:44:45 INFO - 'XPC_SERVICE_NAME': '0', 19:44:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:44:45 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-o7jmcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:44:45 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-iOiTLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:44:45 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-IJwXIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:44:45 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-rOHmIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:44:45 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-IW1Cy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:44:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:44:45 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-eB7XE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-7KjGGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-C7Zskg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-YByUVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-cHvNZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-WJxu8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-oQuNb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Bbeqc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-e5cCcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:44:46 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-nHgBdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:44:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-L1qV8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:44:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:44:47 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-hGdo2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:44:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:44:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:44:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:44:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:44:49 INFO - Downloading blessings-1.6.tar.gz 19:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:44:49 INFO - Installing collected packages: blessings 19:44:49 INFO - Running setup.py install for blessings 19:44:49 INFO - Successfully installed blessings 19:44:49 INFO - Cleaning up... 19:44:50 INFO - Return code: 0 19:44:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:44:50 INFO - Reading from file tmpfile_stdout 19:44:50 INFO - Current package versions: 19:44:50 INFO - blessings == 1.6 19:44:50 INFO - blobuploader == 1.2.4 19:44:50 INFO - docopt == 0.6.1 19:44:50 INFO - functools32 == 3.2.3-2 19:44:50 INFO - jsonschema == 2.5.1 19:44:50 INFO - manifestparser == 1.1 19:44:50 INFO - mozInstall == 1.12 19:44:50 INFO - mozcrash == 0.17 19:44:50 INFO - mozdebug == 0.1 19:44:50 INFO - mozdevice == 0.48 19:44:50 INFO - mozfile == 1.2 19:44:50 INFO - mozhttpd == 0.7 19:44:50 INFO - mozinfo == 0.9 19:44:50 INFO - mozleak == 0.1 19:44:50 INFO - mozlog == 3.3 19:44:50 INFO - moznetwork == 0.27 19:44:50 INFO - mozprocess == 0.23 19:44:50 INFO - mozprofile == 0.28 19:44:50 INFO - mozrunner == 6.12 19:44:50 INFO - mozscreenshot == 0.1 19:44:50 INFO - mozsystemmonitor == 0.3 19:44:50 INFO - moztest == 0.7 19:44:50 INFO - mozversion == 1.4 19:44:50 INFO - psutil == 3.1.1 19:44:50 INFO - requests == 1.2.3 19:44:50 INFO - wsgiref == 0.1.2 19:44:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:50 INFO - 'HOME': '/Users/cltbld', 19:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:50 INFO - 'LOGNAME': 'cltbld', 19:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:50 INFO - 'MOZ_NO_REMOTE': '1', 19:44:50 INFO - 'NO_EM_RESTART': '1', 19:44:50 INFO - 'PAGER': '/bin/cat', 19:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:50 INFO - 'PWD': '/builds/slave/test', 19:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:50 INFO - 'SHELL': '/bin/bash', 19:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:50 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:50 INFO - 'USER': 'cltbld', 19:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:50 INFO - 'XPC_FLAGS': '0x0', 19:44:50 INFO - 'XPC_SERVICE_NAME': '0', 19:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:44:50 INFO - Cleaning up... 19:44:50 INFO - Return code: 0 19:44:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:44:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105e77a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f0c618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe980497440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:44:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:44:50 INFO - 'HOME': '/Users/cltbld', 19:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:44:50 INFO - 'LOGNAME': 'cltbld', 19:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:44:50 INFO - 'MOZ_NO_REMOTE': '1', 19:44:50 INFO - 'NO_EM_RESTART': '1', 19:44:50 INFO - 'PAGER': '/bin/cat', 19:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:44:50 INFO - 'PWD': '/builds/slave/test', 19:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:44:50 INFO - 'SHELL': '/bin/bash', 19:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:44:50 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:44:50 INFO - 'USER': 'cltbld', 19:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:44:50 INFO - 'XPC_FLAGS': '0x0', 19:44:50 INFO - 'XPC_SERVICE_NAME': '0', 19:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:44:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:44:50 INFO - Cleaning up... 19:44:50 INFO - Return code: 0 19:44:50 INFO - Running post-action listener: _resource_record_post_action 19:44:50 INFO - Running post-action listener: _start_resource_monitoring 19:44:50 INFO - Starting resource monitoring. 19:44:50 INFO - [mozharness: 2016-08-31 02:44:50.932432Z] Finished create-virtualenv step (success) 19:44:50 INFO - [mozharness: 2016-08-31 02:44:50.933513Z] Running install step. 19:44:50 INFO - Running pre-action listener: _resource_record_pre_action 19:44:50 INFO - Running main action method: install 19:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:44:51 INFO - Reading from file tmpfile_stdout 19:44:51 INFO - Detecting whether we're running mozinstall >=1.0... 19:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:44:51 INFO - Reading from file tmpfile_stdout 19:44:51 INFO - Output received: 19:44:51 INFO - Usage: mozinstall [options] installer 19:44:51 INFO - Options: 19:44:51 INFO - -h, --help show this help message and exit 19:44:51 INFO - -d DEST, --destination=DEST 19:44:51 INFO - Directory to install application into. [default: 19:44:51 INFO - "/builds/slave/test"] 19:44:51 INFO - --app=APP Application being installed. [default: firefox] 19:44:51 INFO - mkdir: /builds/slave/test/build/application 19:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:45:25 INFO - Reading from file tmpfile_stdout 19:45:25 INFO - Output received: 19:45:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:45:25 INFO - Running post-action listener: _resource_record_post_action 19:45:25 INFO - [mozharness: 2016-08-31 02:45:25.458091Z] Finished install step (success) 19:45:25 INFO - [mozharness: 2016-08-31 02:45:25.458248Z] Running run-tests step. 19:45:25 INFO - Running pre-action listener: _resource_record_pre_action 19:45:25 INFO - Running main action method: run_tests 19:45:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:45:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:45:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:45:25 INFO - 2016-08-30 19:45:25.473 screenresolution[1931:19297] starting screenresolution argv=screenresolution get 19:45:25 INFO - 2016-08-30 19:45:25.476 screenresolution[1931:19297] Display 0: 1600x1200x32@60 19:45:25 INFO - 2016-08-30 19:45:25.481 screenresolution[1932:19299] starting screenresolution argv=screenresolution list 19:45:25 INFO - Available Modes on Display 0 19:45:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:45:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:45:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:45:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:45:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:45:25 INFO - Intel Iris: 19:45:25 INFO - Chipset Model: Intel Iris 19:45:25 INFO - Type: GPU 19:45:25 INFO - Bus: Built-In 19:45:25 INFO - VRAM (Dynamic, Max): 1536 MB 19:45:25 INFO - Vendor: Intel (0x8086) 19:45:25 INFO - Device ID: 0x0a2e 19:45:25 INFO - Revision ID: 0x0009 19:45:25 INFO - Displays: 19:45:25 INFO - Display: 19:45:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:45:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:45:25 INFO - Display Serial Number: 1600x1200 60 19:45:25 INFO - Main Display: Yes 19:45:25 INFO - Mirror: Off 19:45:25 INFO - Online: Yes 19:45:25 INFO - Rotation: Supported 19:45:25 INFO - Return code: 0 19:45:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:45:25 INFO - Python 2.7.3 19:45:25 INFO - Return code: 0 19:45:25 INFO - grabbing minidump binary from tooltool 19:45:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:45:25 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ddbd30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b40db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105dd3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:45:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:45:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:45:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:45:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:45:25 INFO - Return code: 0 19:45:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:45:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:45:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:45:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:45:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:45:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:45:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U17ndrUzbr/Render', 19:45:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:45:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:45:25 INFO - 'HOME': '/Users/cltbld', 19:45:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:45:25 INFO - 'LOGNAME': 'cltbld', 19:45:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:45:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:45:25 INFO - 'MOZ_NO_REMOTE': '1', 19:45:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:45:25 INFO - 'NO_EM_RESTART': '1', 19:45:25 INFO - 'PAGER': '/bin/cat', 19:45:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:45:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:45:25 INFO - 'PWD': '/builds/slave/test', 19:45:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:45:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:45:25 INFO - 'SHELL': '/bin/bash', 19:45:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners', 19:45:25 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 19:45:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:45:25 INFO - 'USER': 'cltbld', 19:45:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:45:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:45:25 INFO - 'XPC_FLAGS': '0x0', 19:45:25 INFO - 'XPC_SERVICE_NAME': '0', 19:45:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:45:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:45:26 INFO - mozversion application_buildid: 20160830173521 19:45:26 INFO - mozversion application_changeset: 5931a8286060ca165423aba08b165e0c8bff71d9 19:45:26 INFO - mozversion application_display_name: Nightly 19:45:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:45:26 INFO - mozversion application_name: Firefox 19:45:26 INFO - mozversion application_remotingname: firefox 19:45:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:45:26 INFO - mozversion application_vendor: Mozilla 19:45:26 INFO - mozversion application_version: 51.0a1 19:45:26 INFO - mozversion platform_buildid: 20160830173521 19:45:26 INFO - mozversion platform_changeset: 5931a8286060ca165423aba08b165e0c8bff71d9 19:45:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:45:26 INFO - mozversion platform_version: 51.0a1 19:45:26 INFO - using testdate: 1472611526 19:45:26 INFO - actual date: 1472611526 19:45:26 INFO - starting webserver on 'localhost:56117' 19:45:26 INFO - SUITE-START | Running 2 tests 19:45:26 INFO - TEST-START | dromaeo_css 19:45:26 INFO - operating with platform_type : mac_ 19:45:26 INFO - Initialising browser for dromaeo_css test... 19:45:26 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpciSZBN/profile http://localhost:56117/getInfo.html) 19:45:28 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 19:45:28 INFO - PROCESS | 1942 | colorDepth:24 19:45:28 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 19:45:28 INFO - PROCESS | 1942 | __metrics 19:45:28 INFO - PROCESS | 1942 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:45:28 INFO - PROCESS | 1942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:45:29 INFO - TEST-INFO | 1942: exit 0 19:45:29 INFO - Browser initialized. 19:45:29 INFO - Running cycle 1/1 for dromaeo_css test... 19:45:29 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpciSZBN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:45:41 INFO - PROCESS | 1944 | RSS: Main: 234745856 19:45:41 INFO - PROCESS | 1944 | 19:46:32 INFO - PROCESS | 1944 | 1472611592624 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:48:44 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56117/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56117/tests/dromaeo/cssquery-ext.html) 19:48:45 INFO - PROCESS | 1944 | RSS: Main: 369565696 19:48:45 INFO - PROCESS | 1944 | 19:51:48 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56117/tests/dromaeo/cssquery-ext.html (next: http://localhost:56117/tests/dromaeo/cssquery-jquery.html) 19:51:49 INFO - PROCESS | 1944 | RSS: Main: 372051968 19:51:49 INFO - PROCESS | 1944 | 19:54:51 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56117/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56117/tests/dromaeo/cssquery-mootools.html) 19:54:52 INFO - PROCESS | 1944 | RSS: Main: 359370752 19:54:52 INFO - PROCESS | 1944 | 19:57:54 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56117/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56117/tests/dromaeo/cssquery-prototype.html) 19:57:55 INFO - PROCESS | 1944 | RSS: Main: 334630912 19:57:55 INFO - PROCESS | 1944 | 20:00:58 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56117/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56117/tests/dromaeo/cssquery-yui.html) 20:00:59 INFO - PROCESS | 1944 | RSS: Main: 324997120 20:00:59 INFO - PROCESS | 1944 | 20:04:02 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:56117/tests/dromaeo/cssquery-yui.html (next: http://localhost:56117/tests/dromaeo/cssquery-dojo.html) 20:04:03 INFO - PROCESS | 1944 | RSS: Main: 487047168 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | __start_tp_report 20:04:03 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 20:04:03 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 20:04:03 INFO - PROCESS | 1944 | |i|pagename|runs| 20:04:03 INFO - PROCESS | 1944 | |0;dojo.html;3297.076818490822;3310;3336.1326055225636;3368.5284060231556;3405.5913290405156;4953.157963146269;5512.476314750103;5656.123300888358;5679.233303504026;5770.3364113127;5665.631733937302;5734.397888221739;5739.827805165852;5748.568857335696;5756.338021127572;5168.198929165976;5212.478752124776;5220.686758794484;5230.581553475713;5287.365516138068;5249.448807875176;5251.76367063483;5255.500727430888;5257.842264732064;5261.473852614746;5214.817481388152;5227.843164705046;5238.292830467895;5238.4499627539135;5251.264822924793;5291.999999999981;5293.947060529384;5299.758291397573;5320.388155362144;5328.866778330534;5033.219850923077;5059.165237735806;5145.974270128663;5247.107991641392;5256.789728410859;4621.976890115561;5649.830505084854;5683.886322273532;5698.914516282218;5780.797672081457;6231.626102433868;6238.2825975013;6249.218847644044;6251.718672659741;6253.6873156342;6131.96934015327;6188.381161883821;6238.9376106238815;6276.941392395654;6331.525135614847;5818.185454036354;5824.271966004249;5830.7959221427045;5846.239988801429;5876.089206173007;6238.625682459067;6246.906296405557;6255.749770009149;6256.624602523863;6277.246730392382;5953.702314884238;5958.553108516879;6042.456178943916;6136.447719705248;6173.351798061139;6158.3225845157895;6196.504279657616;6229.844253893689;6237.781677641261;6303.117563541108;6234;6241;6257.906131408033;6261;6265.154204182468;6113.296970848321;6179.474744646755;6266.811995640138;6269.968650156811;6275.748970041239;4852;4868;4934.988327392876;4944.307796908436;4990.650654454226;4430.911381772411;4443.311286750559;4447.488538818077;4458.152950939311;4494.393256910341;5750.338711048199;5769.769209231584;5772.624779389326;5783.508401785895;5794.449527294901;6126.517673797367;6136.610985192662;6188.350223226586;6246.875062498815;6275.309715931244;5681.34664513578;5720.170575266647;5736.770529178786;5740.483356497935;5774.249347584871;5361.0618141825025;5365.1147560652835;5365.919511207335;5372.516473517402;5385.057614917374;5206.297149884717;6212.937870621417;6259.812205633838;6282.403171698681;6309.337519560564;6280.1207830902795;6281.560290779785;6297.716602752796;6299.842503937255;6310.116583678197;6291.685415729286;6298.307186209422;6315.873682526232;6316.084167795552;6356.364363563606;6070.180525628984;6115.727150604437;6138.3883983858805;6253.812385628438;6253.937460625518;6108.633481991094;6243.937560624336;6275.058741188858;6284.685765711604;6302.338254483213;6119.632822030692;6129.724162412791;6153.323134455117;6153.507719382349;6159.168512250968;5867;6082.908756368746;6112.388761123852;6137.3248942617165;6154.661493617715;6024.789132380346;6035.426634469718;6052.061930400619;6072.271327440559;6079.513638908788;6103.515139462075;6113.345812258461;6145.033589251575;6145.539084568764;6153.723082461211;5863.648181109147;5899.203607512931;5939.4951429129005;5958.970205148947;6035.637861728311 20:04:03 INFO - PROCESS | 1944 | |1;ext.html;479.57317986991563;482.1802935010483;500.589516596391;508.02712804978444;546.5955193157065;30677.07968760938;32046.519302210458;39401;40338;40436.79781601095;42333.57666423335;42644.14711705764;42669.573304266945;42692;42738.14523709532;9688.128068473854;9865.358751681153;9865.457399843006;9896.604135834557;9897.208223342152;9720.562574684122;9820.852687209697;9831.705048848511;9849.310548261625;9873.60505579776;9802.36284641496;9850.261230407712;9855.753606159868;9875.55559999798;9887.703368898909;8369.665213391458;9777.31558790885;9811.65659201928;9842.852357214648;9910.653127140515;9690.079442452956;9760.609575617038;9767.706968790877;9772.95113524435;9791.804163916682;40035.19929601392;40394.79802600968;40428;40435.797821011;40444.393334100016;3339.916502087443;3369;3537.168765340143;3713.424419215026;3808.295465338921;29862.70137298621;30035.098947031624;30442.086737397913;30623.387532249453;30750.53874191889;3149.3228955774616;3161.525771134325;3210.7110360067704;3222.130024893289;3245.221146924721;473903;474173.2582674164;476285;478010;487509;55559.44440555543;55817.720911396;55846;55889.7205513978;56191;32637.347253054806;33151.66848331534;58975;59262;59278;16177.029378237108;16692.499225023268;16742.58143546421;16762.916185418995;16785.076820774975;12676.23942563449;12729.172603780724;12739.044571657163;12746.936265318614;12785.360731963365;18652;18658;18665.90667046656;18669.346572869883;18700.53248668767;6520.739170433224;7434.36807871337;7558.962205188939;7610.771676849703;7670.079590449071;833.9207775261341;911.4531281231341;926.4528237842819;946.8011717539256;966.2270183852889;22693.546129077655;24463;30054.398912021636;30437.69562304393;30582.388352232832;1016.8322226832638;1033.1115240892825;1067.4716015572342;1095.780843831221;1126.7633747012173;12295.077869159848;15440.691186176438;15560.766588501181;15687.058776473448;16037.759433608506;654789;657122;659203;669877.6506117438;672860;57047.71476142676;57639;57652.42347576471;57775.555611109216;58493.70753146292;1213.7329787446924;1342.5166939901628;1351.905366624327;1356.8371795384617;1382.3019375215451;29732.702672972995;31330.373392531565;31448.685513144577;31588.842055789573;32393.676063239065;49348.2597761041;50236;50243.99512009666;50244.74877625588;50421.74789126031;560131;566068;566219;570442.8867133993;571167;35307.47038794471;35424.46863297001;42686.35970460508;42834;44309;46577.068458631315;46802;47025;47343.763281183376;47930.80172995703;35586.64413355937;36798;38430.6156938427;39051.41422878602;40655.59344406518;37590.43614345732;38424.2315153701;39188.216235674554;39787.602123979545;39924.00189995273;37837.43243851289;38268.425973610974;38575.42136867893;38901.61098389093;38969.610303896596;30536.541951870295;32024.359512809147;34205;34511.13722156866;34947.30105397827;22844.85775711241;25106.24681259565;25638;26203.082892098686;26844 20:04:03 INFO - PROCESS | 1944 | |2;jquery.html;275.7449359342608;330.7023678689179;345.3145506170253;377.5978582809308;388.25455126157794;15289.082655040706;16038.599035024115;19209.75136616119;20584.485387865312;20995.685064724003;17090.401835935743;19842.908640024794;20667.96660166993;20893.164273429084;21166.153353865866;5805.7967971121125;6120.112583675386;6134.539909506782;6136.938630613704;6139.079138129294;4193.35003074524;5093.490650934914;6048.879022419571;6100.267967843843;6110.572259941806;4406.911861762763;5574.9721251393885;6325.5255855811;6351.364863513658;6671.199456065279;6379;6516.739330426777;6601.801945941582;6686.331366863324;6693.2302785179845;5670.121131224626;5678.801241956512;5680.744366503517;5688.061469857468;5696.857578560528;24753.25740227796;25650.871745641154;25748.227553173434;25881.870590647115;26120.34699132518;2183.912643494258;2192.365957235447;2233.9776602233933;2400.1599440195964;2895.1604034829875;14654.560363189015;15109.848901511064;15298.388064477407;15362.692746145014;15518.680912122483;1791.8835275707038;1818.8090250523637;1888.2352647177916;1890.3779267366906;1895.0714150066506;84020.99999999939;104362.47818760858;106166;107027.46486267597;107172.39241411386;10494.632687856041;10625.362478251329;10764.515596798163;10765.461726913623;10829.67510974672;13009.934950325187;13047.543335983195;13062.085654004128;13064.673383165496;13070.934645326903;8235.83528329442;8413.116622754646;8413.95793021031;8418.957905210435;8561.700340488052;8464.365172612079;8482.872756908651;8639.481631102153;8682.131786821268;8698.956505217433;6254.405831446005;6355.0785136155;6366.140571022853;6367.299597044322;6384.808455746335;1574.6457047164297;1772.9468115956543;1799.2892807341027;1810.357323150289;1812.8187181281767;708.4863473981322;943.3632298198826;946.5645802930592;965.5365424596092;1067.199600299775;21060.05229764664;21152.259670911444;22203.77796222017;22290.665640015413;22340.776592234193;955.4918339359182;956.9951550871557;975.5512464266375;1003.2074661017714;1027.1833892055834;8938.776530586658;10309.793804123876;11006.944965275123;11075.833862492069;11169;566521;568588;578940;581341.1865881287;583843;136300;137859;139151;141392;143281.28359358138;975.1175186456127;976.5410257179115;1007.4459047523833;1007.7682133109279;1016.6238491757949;18455.89264644072;18675.43973680792;18697.25210991545;18698.34555790541;18709.25162993465;51730.48269517407;52023;52607.73696131495;53545.46454535405;53838.73080634572;74921.12697182615;75140.49719005698;76790.61604692164;76793.84809227978;77739;7941.8411631768195;7955.204479551999;8172.141925098017;8480.15198480147;8490;11688.590899318484;12163.635090947286;12525.37373131358;12599.18105323151;13220.272884991418;10817.729556761144;11080.722981925517;11318.660440186808;11328.32030078165;11391.601293954673;10762.892371076188;10933.289336193122;11115;11238.494267758135;11522.251053681484;9631.951840240754;10404.843927341248;10895.836562451728;11322.853255742466;11502.654920352403;7943.563104029334;8023.438359314792;8239.95880020596;8250.46371985819;8410.789730256547;8358.205970432798;10560.366378017343;10670.253082284476;11224.887751122384;11385.658430247106 20:04:03 INFO - PROCESS | 1944 | |3;mootools.html;1495.401839264294;1524.108396587996;1707.906065166416;1824.8175182481746;1900.9239630414784;8489.702860399888;9025.323100767437;9032.638694452231;9033.187013168814;9034.954825225881;7814.452988290822;8053.275205231528;8567;8763.430377025488;8806.383553151281;2374.821888358376;2411.4815314707334;2414.28778510339;2417.9274621761374;2423.1882319423016;2011.959760804783;2391.175044609607;2405.374602603327;2406.133791834947;2410.8553486790847;2372.540887354282;2396.7363590005084;2407.7712617301327;2414.5050264695697;2415.915442959497;2371.739108698042;2375.5367703297866;2387.057112440585;2388.7850093491497;2409.74697656747;2364.9054037838464;2379.250536081146;2390.653355263477;2390.9402264943515;2399.9160029398886;8891.4665120093;8893.4663920165;8951.41815781972;8955.149260820212;8987.595558199897;5223.764930578133;5381.704006279653;5382.111951527993;5390.353157621072;5397.919031214535;3493.056874643832;3498.860045598173;3572.1962558424407;3572.7856328620364;3574.303010912873;938.8874184091873;1039.3763741754963;1043.979120417595;1051.285126114242;1062.378508572486;11321.547138114465;11640.417979101096;11794.35131067791;11810.822837657528;11843.822342664867;4584.61030812378;4643;4777.092352453023;4785.1625965455905;4834.468208497063;9987.700368988797;10036.197104231644;10057.64798232073;10066.547005384775;10094.09153176203;5346.732663366816;5348.384935732441;5351.384590772033;5355.86610334737;5376.4085950545405;4360.738355698668;4516.164509565675;4719.10337035962;4830.8309209178215;4878.219449900832;3860.034991252187;3902.863399780994;4012.337964235868;4024.054347228399;4082.9183416332103;1290.393515047926;1294.1717300927414;1296.7665820152274;1308.8167656528094;1309.8362704661918;754.5849782619538;844.2612713875359;884.6859364925488;889.4840992224495;927.4759760735163;5393.6763794172475;5636.379998200195;5672.7730890763905;5904.852378690567;6086.513078953673;927.5408672707053;927.6567669962158;943.7027336389016;945.6784693204344;988.0662773678948;3069.1713237426216;3887.1642596841816;3914.256291304643;3920.09837737322;4024.0342317844093;10614.787704246026;10835.67492975212;10958.178136639784;10964.780704385868;10979;10902.073323767569;10908.836367454494;10917.727056823644;10935.671929842118;10984.121270298358;8124.431289809657;8268.958655206805;8271.379646526535;8279.54462504568;8282.58587070644;5338.225957236102;5427.674339539641;5709.9714501427225;5843;5951.1073338999495;9983.400995940267;10201;10227.488625568543;10248.64129755455;10259.076683098558;9633.036696330311;10136.239782016231;10174.74563135898;10315.48422578853;10345.482725863827;6893.758718445031;7152.320529549685;7380.815479613053;7458.627068646654;7659.157492675913;8414.663413463393;8453.492790432594;8580.485170889766;8584.613692383982;8614.82770344602;7350.632468376452;7441.330280274801;7516;7600.391968642606;7700.884486732815;6029.939700602938;7220.1696804866015;7333.303336183054;7415.406767458482;7501.699932002659;6932.098827152639;6964.338387853146;7017.087778588751;7032.718691252498;7283.980242766123;4929.334539837076;4975.452700203047;5001.999600080071;5040.949590504048;5146.485351464824;4893.045856058035;5053.823116190915;5079.77141028662;5120.18077107661;5227.424983251915 20:04:03 INFO - PROCESS | 1944 | |4;prototype.html;295.28099078743264;300.14458792441536;308.92122508760264;311.4782738912321;312.7966821565982;4741.620670346372;5209.583233341333;5354.598405119612;5383.950129724705;5396.865078373043;4283.828646854122;4948.876278093049;5134.127198376275;5251.5798736101115;5334.359876814778;4073.4093556434373;4087.1825634872994;4094.61101195386;4105.733127346728;4113.156802855422;4063.349864021757;4088.2641124597562;4102.79486025699;4102.938455923164;4118.8970275743195;3745.681617062553;3910.608939106095;3968.7420317679403;4025.778582177979;4047.7166598338126;4002.5397079335958;4004.6796256299426;4021.658159056484;4048.1701251243426;4057.594240575963;2911.3595009097967;3432.7768695034742;3432.8111953842526;3452.2922800825772;3462.394081035834;4180.519240287375;4884.853454396374;5042.621803364726;5120.615953803443;5135.8202462914;3769.8492060317553;4363.694541382105;4522.479914809774;4542.341360502709;4558.222293305614;5496.7526461309335;5559.555235581184;5573.972130139364;5583.162525621149;5589.692566908819;3373.561437013197;3719.0516418313427;3730.9253814923823;3746.7189960753053;3753.6997040236715;5444.727763611843;5666.4050274721385;5706.258186435778;5773.5958482906635;5801.694618710799;3782.962170378316;3797.9175934858986;3804;3854.9376083795037;3857.4792403025795;3915.804209789499;3925.7448265862627;3939.783311917872;3961.286968345668;3965.7111438782513;3532.8410221540094;3621.927561448809;3969.7221194516683;4033.3748269018347;4482.686211965196;5469.261649677323;5519.254900588449;5559.58303127259;5564.165375193672;5576.33084029919;1242.6023672424737;1310.7509573181255;1328.4752522753458;1331.2145833958011;1342.64419928717;699.5487910297829;745.5042396806064;833.3166803221301;890.3322508118911;890.7796319042823;4692.976535117303;4698.788554515055;5062.316587260672;5064.873378165502;5198.454162312978;4496.212348344047;4814.542618451242;5032.043911656773;5519.31008623922;5543.501084902378;4220.53574106848;4967.279744437036;5064.392272927273;5084.9745751271;5087.9824035193105;2217.9001944912516;2743.2181828178873;2897.3625802323454;2910.767138628905;2930.956035659467;5500.119980803132;5760.596758226968;5765.625234359753;5784.942150578525;5788.623739456922;5359.383670877821;5661.773529058792;5693.28833895763;5711.828645140652;5765.3946335634155;4622.352870598119;5663.037283661787;5737.454941780607;5795.130730390475;5824.912626310694;4757.024809914029;5207.453217412117;5566.608347913022;5577.693226872561;5623.662580245198;4778.856634300976;4832.540908613676;5208.296879921162;5310.707911064928;5583.692896890548;5262.842114736564;5430.429804870431;5659.943400566106;6016.157737916607;6021.7591296347655;3224.790388624732;3287.964291248249;3418.4530475124857;3806.9428958566195;3845.8846234613006;4570.520095389937;4674.088552732321;4696.8590942271785;4796.37647105874;5628.971855140698;5341.679499230058;5519.061759500974;5521.503064724194;5557.527610153021;5616.550675945833;3995.141044675409;4921.990991846591;5318.494742999409;5399.379071406682;5552.58355623338;4583.862484125481;5244.659097158673;5538.889222215614;5556.416576259435;5560.443955604407;5225;5360.65155764874;5555.50000499957;5687.289088863892;5688.146777983336;4445.755483448312;4678.017616300615;5472.823342981278;5478.753456094565;5505.862353441035 20:04:03 INFO - PROCESS | 1944 | |5;yui.html;616.2512547255084;696.7909627111867;725.648060690565;761.0828951113908;803.8995125609299;15406.92296538517;15682.921585392085;16004.279807408666;16007.519774406772;16011.199440028011;13784.104033237832;13864.722705545882;13871.375788089535;13924.651883702913;14298;663.051835874699;683.9110915580967;687.3092541995288;688.1157712339644;825.1418524734285;681.6523572977792;685.6811582614083;813.9552324622143;839.6137776622761;842.4987132656059;873.7422460374173;881.1188811188811;889.6619284671816;889.7330800759779;891.9152680495375;809.7975506123469;811.6225954930926;875.5490922175085;878.2359347367831;880.0978996528578;527.5304978569077;542.1055258822935;656.2092678322609;657.1030543308398;658.7430901948244;12275.631731048083;12342.629721108291;12343.814842777365;14145;14711.190884501348;14241.85758142426;14762.778558321634;14846.331915063847;14853.183074930874;14899.404023839033;11800.527978880835;11860.169788114836;11879.40602969848;11960.282383057045;12302.200356976768;3823.808809559511;3886.125621735087;3906.8241929112974;3930.5479869815053;3947.4473573699706;117881.41059294649;117998.41000794941;118897.81102188955;119153.40423297828;119861.40069299685;9782.9021709782;11002.669919902415;11205.831912521318;11271.436428178558;11721.4139293035;20490.692639610417;21092.05085771144;23163;23180.18869339565;23214.419639509146;7821.882671759929;7881.881771773428;7984.960075199587;7991.560464174526;8072.112067672551;3654.3422184007136;4353;4358.128374325148;4366.890827729332;4513.413256276729;3448.4827275908815;3574.4638304254568;3583.623719509414;3586.49367265749;3588.4796704477703;649.782322921817;661.5997321620447;668.9531732778756;672.1933679584519;674.3425160468602;575.9107338362561;605.7819185093363;667.3593350383686;667.951316433205;673.7271405080852;8735.781605459915;9057.18485336323;9229.353945223904;9352.579133938989;9463;574.7729646789495;622.9501639868799;646.3697894552868;658.5686375424104;679.5854528737466;3908.6677632400997;4206.768627725504;4332.415123958288;4521.11838191556;4575.37821068249;99297;118392.34468897841;130515;159423;159659;134694;136508.31745841407;139309.3034534841;140204;143892;96128.51935740277;96726.51636741911;116239.41880290545;116671;117755.41122294334;9512.476813775309;9853.950730246303;9866.309358345134;9905;9976.15202707778;59679;60584.394156059636;60705.78588428295;61671.6916415415;62117.689411554464;35864.82067589645;59199.40800591939;72642.27357726566;89617;94041;12981.675458113623;13189.604311870658;13311.267880266678;13330;13546.86453135456;14890.627734306729;14911.25443727831;15262.923685381502;15346.232688365313;15414.922925385301;13727.382267798173;13826.516071937434;14015;14038.78941815853;14657;13664.316784160554;13840.792388113985;13935.790963135358;13956.371963261474;14274.571762847132;13452.730945381234;13557;13607.319634018457;13733.176009439465;13941.651458713613;11016.779664406507;11457.19799614019;11654.592089277174;11700.6653103865;11907.821382679094;9906.356086854332;9943.651972180938;11281.71795705114;11426.2001659883;11568.942155289169 20:04:03 INFO - PROCESS | 1944 | __end_tp_report 20:04:03 INFO - PROCESS | 1944 | __start_cc_report 20:04:03 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,1217 20:04:03 INFO - PROCESS | 1944 | __end_cc_report 20:04:03 INFO - PROCESS | 1944 | __startTimestamp1472612643240__endTimestamp 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | ------- Summary: start ------- 20:04:03 INFO - PROCESS | 1944 | Number of tests: 6 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5739.99 Median:5958.97 stddev:645.89 (10.8%) stddev-sans-first:618.79 20:04:03 INFO - PROCESS | 1944 | Values: 3297.1 3310.0 3336.1 3368.5 3405.6 4953.2 5512.5 5656.1 5679.2 5770.3 5665.6 5734.4 5739.8 5748.6 5756.3 5168.2 5212.5 5220.7 5230.6 5287.4 5249.4 5251.8 5255.5 5257.8 5261.5 5214.8 5227.8 5238.3 5238.4 5251.3 5292.0 5293.9 5299.8 5320.4 5328.9 5033.2 5059.2 5146.0 5247.1 5256.8 4622.0 5649.8 5683.9 5698.9 5780.8 6231.6 6238.3 6249.2 6251.7 6253.7 6132.0 6188.4 6238.9 6276.9 6331.5 5818.2 5824.3 5830.8 5846.2 5876.1 6238.6 6246.9 6255.7 6256.6 6277.2 5953.7 5958.6 6042.5 6136.4 6173.4 6158.3 6196.5 6229.8 6237.8 6303.1 6234.0 6241.0 6257.9 6261.0 6265.2 6113.3 6179.5 6266.8 6270.0 6275.7 4852.0 4868.0 4935.0 4944.3 4990.7 4430.9 4443.3 4447.5 4458.2 4494.4 5750.3 5769.8 5772.6 5783.5 5794.4 6126.5 6136.6 6188.4 6246.9 6275.3 5681.3 5720.2 5736.8 5740.5 5774.2 5361.1 5365.1 5365.9 5372.5 5385.1 5206.3 6212.9 6259.8 6282.4 6309.3 6280.1 6281.6 6297.7 6299.8 6310.1 6291.7 6298.3 6315.9 6316.1 6356.4 6070.2 6115.7 6138.4 6253.8 6253.9 6108.6 6243.9 6275.1 6284.7 6302.3 6119.6 6129.7 6153.3 6153.5 6159.2 5867.0 6082.9 6112.4 6137.3 6154.7 6024.8 6035.4 6052.1 6072.3 6079.5 6103.5 6113.3 6145.0 6145.5 6153.7 5863.6 5899.2 5939.5 5959.0 6035.6 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:69997.97 Median:40436.30 stddev:153479.18 (379.6%) stddev-sans-first:153820.98 20:04:03 INFO - PROCESS | 1944 | Values: 479.6 482.2 500.6 508.0 546.6 30677.1 32046.5 39401.0 40338.0 40436.8 42333.6 42644.1 42669.6 42692.0 42738.1 9688.1 9865.4 9865.5 9896.6 9897.2 9720.6 9820.9 9831.7 9849.3 9873.6 9802.4 9850.3 9855.8 9875.6 9887.7 8369.7 9777.3 9811.7 9842.9 9910.7 9690.1 9760.6 9767.7 9773.0 9791.8 40035.2 40394.8 40428.0 40435.8 40444.4 3339.9 3369.0 3537.2 3713.4 3808.3 29862.7 30035.1 30442.1 30623.4 30750.5 3149.3 3161.5 3210.7 3222.1 3245.2 473903.0 474173.3 476285.0 478010.0 487509.0 55559.4 55817.7 55846.0 55889.7 56191.0 32637.3 33151.7 58975.0 59262.0 59278.0 16177.0 16692.5 16742.6 16762.9 16785.1 12676.2 12729.2 12739.0 12746.9 12785.4 18652.0 18658.0 18665.9 18669.3 18700.5 6520.7 7434.4 7559.0 7610.8 7670.1 833.9 911.5 926.5 946.8 966.2 22693.5 24463.0 30054.4 30437.7 30582.4 1016.8 1033.1 1067.5 1095.8 1126.8 12295.1 15440.7 15560.8 15687.1 16037.8 654789.0 657122.0 659203.0 669877.7 672860.0 57047.7 57639.0 57652.4 57775.6 58493.7 1213.7 1342.5 1351.9 1356.8 1382.3 29732.7 31330.4 31448.7 31588.8 32393.7 49348.3 50236.0 50244.0 50244.7 50421.7 560131.0 566068.0 566219.0 570442.9 571167.0 35307.5 35424.5 42686.4 42834.0 44309.0 46577.1 46802.0 47025.0 47343.8 47930.8 35586.6 36798.0 38430.6 39051.4 40655.6 37590.4 38424.2 39188.2 39787.6 39924.0 37837.4 38268.4 38575.4 38901.6 38969.6 30536.5 32024.4 34205.0 34511.1 34947.3 22844.9 25106.2 25638.0 26203.1 26844.0 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:34297.32 Median:13576.71 stddev:96274.75 (709.1%) stddev-sans-first:96510.94 20:04:03 INFO - PROCESS | 1944 | Values: 275.7 330.7 345.3 377.6 388.3 15289.1 16038.6 19209.8 20584.5 20995.7 17090.4 19842.9 20668.0 20893.2 21166.2 5805.8 6120.1 6134.5 6136.9 6139.1 4193.4 5093.5 6048.9 6100.3 6110.6 4406.9 5575.0 6325.5 6351.4 6671.2 6379.0 6516.7 6601.8 6686.3 6693.2 5670.1 5678.8 5680.7 5688.1 5696.9 24753.3 25650.9 25748.2 25881.9 26120.3 2183.9 2192.4 2234.0 2400.2 2895.2 14654.6 15109.8 15298.4 15362.7 15518.7 1791.9 1818.8 1888.2 1890.4 1895.1 84021.0 104362.5 106166.0 107027.5 107172.4 10494.6 10625.4 10764.5 10765.5 10829.7 13009.9 13047.5 13062.1 13064.7 13070.9 8235.8 8413.1 8414.0 8419.0 8561.7 8464.4 8482.9 8639.5 8682.1 8699.0 6254.4 6355.1 6366.1 6367.3 6384.8 1574.6 1772.9 1799.3 1810.4 1812.8 708.5 943.4 946.6 965.5 1067.2 21060.1 21152.3 22203.8 22290.7 22340.8 955.5 957.0 975.6 1003.2 1027.2 8938.8 10309.8 11006.9 11075.8 11169.0 566521.0 568588.0 578940.0 581341.2 583843.0 136300.0 137859.0 139151.0 141392.0 143281.3 975.1 976.5 1007.4 1007.8 1016.6 18455.9 18675.4 18697.3 18698.3 18709.3 51730.5 52023.0 52607.7 53545.5 53838.7 74921.1 75140.5 76790.6 76793.8 77739.0 7941.8 7955.2 8172.1 8480.2 8490.0 11688.6 12163.6 12525.4 12599.2 13220.3 10817.7 11080.7 11318.7 11328.3 11391.6 10762.9 10933.3 11115.0 11238.5 11522.3 9632.0 10404.8 10895.8 11322.9 11502.7 7943.6 8023.4 8240.0 8250.5 8410.8 8358.2 10560.4 10670.3 11224.9 11385.7 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5734.54 Median:4885.63 stddev:3235.14 (66.2%) stddev-sans-first:3228.54 20:04:03 INFO - PROCESS | 1944 | Values: 1495.4 1524.1 1707.9 1824.8 1900.9 8489.7 9025.3 9032.6 9033.2 9035.0 7814.5 8053.3 8567.0 8763.4 8806.4 2374.8 2411.5 2414.3 2417.9 2423.2 2012.0 2391.2 2405.4 2406.1 2410.9 2372.5 2396.7 2407.8 2414.5 2415.9 2371.7 2375.5 2387.1 2388.8 2409.7 2364.9 2379.3 2390.7 2390.9 2399.9 8891.5 8893.5 8951.4 8955.1 8987.6 5223.8 5381.7 5382.1 5390.4 5397.9 3493.1 3498.9 3572.2 3572.8 3574.3 938.9 1039.4 1044.0 1051.3 1062.4 11321.5 11640.4 11794.4 11810.8 11843.8 4584.6 4643.0 4777.1 4785.2 4834.5 9987.7 10036.2 10057.6 10066.5 10094.1 5346.7 5348.4 5351.4 5355.9 5376.4 4360.7 4516.2 4719.1 4830.8 4878.2 3860.0 3902.9 4012.3 4024.1 4082.9 1290.4 1294.2 1296.8 1308.8 1309.8 754.6 844.3 884.7 889.5 927.5 5393.7 5636.4 5672.8 5904.9 6086.5 927.5 927.7 943.7 945.7 988.1 3069.2 3887.2 3914.3 3920.1 4024.0 10614.8 10835.7 10958.2 10964.8 10979.0 10902.1 10908.8 10917.7 10935.7 10984.1 8124.4 8269.0 8271.4 8279.5 8282.6 5338.2 5427.7 5710.0 5843.0 5951.1 9983.4 10201.0 10227.5 10248.6 10259.1 9633.0 10136.2 10174.7 10315.5 10345.5 6893.8 7152.3 7380.8 7458.6 7659.2 8414.7 8453.5 8580.5 8584.6 8614.8 7350.6 7441.3 7516.0 7600.4 7700.9 6029.9 7220.2 7333.3 7415.4 7501.7 6932.1 6964.3 7017.1 7032.7 7284.0 4929.3 4975.5 5002.0 5040.9 5146.5 4893.0 5053.8 5079.8 5120.2 5227.4 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4417.95 Median:4903.42 stddev:1365.11 (27.8%) stddev-sans-first:1333.41 20:04:03 INFO - PROCESS | 1944 | Values: 295.3 300.1 308.9 311.5 312.8 4741.6 5209.6 5354.6 5384.0 5396.9 4283.8 4948.9 5134.1 5251.6 5334.4 4073.4 4087.2 4094.6 4105.7 4113.2 4063.3 4088.3 4102.8 4102.9 4118.9 3745.7 3910.6 3968.7 4025.8 4047.7 4002.5 4004.7 4021.7 4048.2 4057.6 2911.4 3432.8 3432.8 3452.3 3462.4 4180.5 4884.9 5042.6 5120.6 5135.8 3769.8 4363.7 4522.5 4542.3 4558.2 5496.8 5559.6 5574.0 5583.2 5589.7 3373.6 3719.1 3730.9 3746.7 3753.7 5444.7 5666.4 5706.3 5773.6 5801.7 3783.0 3797.9 3804.0 3854.9 3857.5 3915.8 3925.7 3939.8 3961.3 3965.7 3532.8 3621.9 3969.7 4033.4 4482.7 5469.3 5519.3 5559.6 5564.2 5576.3 1242.6 1310.8 1328.5 1331.2 1342.6 699.5 745.5 833.3 890.3 890.8 4693.0 4698.8 5062.3 5064.9 5198.5 4496.2 4814.5 5032.0 5519.3 5543.5 4220.5 4967.3 5064.4 5085.0 5088.0 2217.9 2743.2 2897.4 2910.8 2931.0 5500.1 5760.6 5765.6 5784.9 5788.6 5359.4 5661.8 5693.3 5711.8 5765.4 4622.4 5663.0 5737.5 5795.1 5824.9 4757.0 5207.5 5566.6 5577.7 5623.7 4778.9 4832.5 5208.3 5310.7 5583.7 5262.8 5430.4 5659.9 6016.2 6021.8 3224.8 3288.0 3418.5 3806.9 3845.9 4570.5 4674.1 4696.9 4796.4 5629.0 5341.7 5519.1 5521.5 5557.5 5616.6 3995.1 4922.0 5318.5 5399.4 5552.6 4583.9 5244.7 5538.9 5556.4 5560.4 5225.0 5360.7 5555.5 5687.3 5688.1 4445.8 4678.0 5472.8 5478.8 5505.9 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:24327.89 Median:3739.08 stddev:39066.34 (1044.8%) stddev-sans-first:39135.36 20:04:03 INFO - PROCESS | 1944 | Values: 616.3 696.8 725.6 761.1 803.9 15406.9 15682.9 16004.3 16007.5 16011.2 13784.1 13864.7 13871.4 13924.7 14298.0 663.1 683.9 687.3 688.1 825.1 681.7 685.7 814.0 839.6 842.5 873.7 881.1 889.7 889.7 891.9 809.8 811.6 875.5 878.2 880.1 527.5 542.1 656.2 657.1 658.7 12275.6 12342.6 12343.8 14145.0 14711.2 14241.9 14762.8 14846.3 14853.2 14899.4 11800.5 11860.2 11879.4 11960.3 12302.2 3823.8 3886.1 3906.8 3930.5 3947.4 117881.4 117998.4 118897.8 119153.4 119861.4 9782.9 11002.7 11205.8 11271.4 11721.4 20490.7 21092.1 23163.0 23180.2 23214.4 7821.9 7881.9 7985.0 7991.6 8072.1 3654.3 4353.0 4358.1 4366.9 4513.4 3448.5 3574.5 3583.6 3586.5 3588.5 649.8 661.6 669.0 672.2 674.3 575.9 605.8 667.4 668.0 673.7 8735.8 9057.2 9229.4 9352.6 9463.0 574.8 623.0 646.4 658.6 679.6 3908.7 4206.8 4332.4 4521.1 4575.4 99297.0 118392.3 130515.0 159423.0 159659.0 134694.0 136508.3 139309.3 140204.0 143892.0 96128.5 96726.5 116239.4 116671.0 117755.4 9512.5 9854.0 9866.3 9905.0 9976.2 59679.0 60584.4 60705.8 61671.7 62117.7 35864.8 59199.4 72642.3 89617.0 94041.0 12981.7 13189.6 13311.3 13330.0 13546.9 14890.6 14911.3 15262.9 15346.2 15414.9 13727.4 13826.5 14015.0 14038.8 14657.0 13664.3 13840.8 13935.8 13956.4 14274.6 13452.7 13557.0 13607.3 13733.2 13941.7 11016.8 11457.2 11654.6 11700.7 11907.8 9906.4 9943.7 11281.7 11426.2 11568.9 20:04:03 INFO - PROCESS | 1944 | -------- Summary: end -------- 20:04:03 INFO - PROCESS | 1944 | 20:04:03 INFO - TEST-INFO | 1944: exit 0 20:04:03 INFO - TEST-OK | dromaeo_css | took 1117798ms 20:04:03 INFO - TEST-START | kraken 20:04:03 INFO - operating with platform_type : mac_ 20:04:03 INFO - Initialising browser for kraken test... 20:04:03 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp11GLwV/profile http://localhost:56117/getInfo.html) 20:04:06 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 20:04:06 INFO - PROCESS | 1946 | colorDepth:24 20:04:06 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 20:04:06 INFO - PROCESS | 1946 | __metrics 20:04:06 INFO - PROCESS | 1946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:04:06 INFO - PROCESS | 1946 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:04:07 INFO - TEST-INFO | 1946: exit 0 20:04:07 INFO - Browser initialized. 20:04:07 INFO - Running cycle 1/1 for kraken test... 20:04:07 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp11GLwV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:04:19 INFO - PROCESS | 1947 | RSS: Main: 234459136 20:04:19 INFO - PROCESS | 1947 | 20:04:21 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56117/tests/kraken/driver.html?testName=audio-beat-detection) 20:04:22 INFO - PROCESS | 1947 | RSS: Main: 291770368 20:04:22 INFO - PROCESS | 1947 | 20:04:25 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56117/tests/kraken/driver.html?testName=audio-dft) 20:04:25 INFO - PROCESS | 1947 | RSS: Main: 287932416 20:04:25 INFO - PROCESS | 1947 | 20:04:29 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56117/tests/kraken/driver.html?testName=audio-fft) 20:04:30 INFO - PROCESS | 1947 | RSS: Main: 381394944 20:04:30 INFO - PROCESS | 1947 | 20:04:32 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56117/tests/kraken/driver.html?testName=audio-oscillator) 20:04:32 INFO - PROCESS | 1947 | RSS: Main: 280961024 20:04:32 INFO - PROCESS | 1947 | 20:04:35 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56117/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:04:35 INFO - PROCESS | 1947 | RSS: Main: 283013120 20:04:35 INFO - PROCESS | 1947 | 20:04:38 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56117/tests/kraken/driver.html?testName=imaging-darkroom) 20:04:39 INFO - PROCESS | 1947 | RSS: Main: 322490368 20:04:39 INFO - PROCESS | 1947 | 20:04:42 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56117/tests/kraken/driver.html?testName=imaging-desaturate) 20:04:42 INFO - PROCESS | 1947 | RSS: Main: 323371008 20:04:42 INFO - PROCESS | 1947 | 20:04:45 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56117/tests/kraken/driver.html?testName=json-parse-financial) 20:04:46 INFO - PROCESS | 1947 | RSS: Main: 320638976 20:04:46 INFO - PROCESS | 1947 | 20:04:47 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56117/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:04:48 INFO - PROCESS | 1947 | RSS: Main: 298741760 20:04:48 INFO - PROCESS | 1947 | 20:04:50 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:04:50 INFO - PROCESS | 1947 | RSS: Main: 303505408 20:04:50 INFO - PROCESS | 1947 | 20:04:52 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:04:53 INFO - PROCESS | 1947 | RSS: Main: 302997504 20:04:53 INFO - PROCESS | 1947 | 20:04:56 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:04:57 INFO - PROCESS | 1947 | RSS: Main: 313032704 20:04:57 INFO - PROCESS | 1947 | 20:05:00 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:05:00 INFO - PROCESS | 1947 | RSS: Main: 315691008 20:05:00 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | Cycle 1(1): loaded http://localhost:56117/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56117/tests/kraken/driver.html?testName=ai-astar) 20:05:02 INFO - PROCESS | 1947 | RSS: Main: 358928384 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | __start_tp_report 20:05:02 INFO - PROCESS | 1947 | _x_x_mozilla_page_load 20:05:02 INFO - PROCESS | 1947 | _x_x_mozilla_page_load_details 20:05:02 INFO - PROCESS | 1947 | |i|pagename|runs| 20:05:02 INFO - PROCESS | 1947 | |0;ai-astar;114;113;138;113;113;114;113;113;119;164 20:05:02 INFO - PROCESS | 1947 | |1;audio-beat-detection;149;108;133;163;107;132;140;133;199;121 20:05:02 INFO - PROCESS | 1947 | |2;audio-dft;186;186;183;181;185;186;184;184;183;183 20:05:02 INFO - PROCESS | 1947 | |3;audio-fft;80;74;77;158;58;57;115;86;175;57 20:05:02 INFO - PROCESS | 1947 | |4;audio-oscillator;83;84;144;70;71;75;120;68;69;120 20:05:02 INFO - PROCESS | 1947 | |5;imaging-gaussian-blur;89;86;90;86;89;87;90;86;90;85 20:05:02 INFO - PROCESS | 1947 | |6;imaging-darkroom;96;97;98;97;98;136;91;163;160;97 20:05:02 INFO - PROCESS | 1947 | |7;imaging-desaturate;83;84;85;84;85;84;93;83;85;83 20:05:02 INFO - PROCESS | 1947 | |8;json-parse-financial;55;56;55;54;54;54;53;53;54;53 20:05:02 INFO - PROCESS | 1947 | |9;json-stringify-tinderbox;66;65;74;79;72;71;69;71;71;70 20:05:02 INFO - PROCESS | 1947 | |10;stanford-crypto-aes;86;84;85;86;86;88;85;86;77;96 20:05:02 INFO - PROCESS | 1947 | |11;stanford-crypto-ccm;141;141;136;138;155;153;140;137;234;139 20:05:02 INFO - PROCESS | 1947 | |12;stanford-crypto-pbkdf2;170;170;170;170;170;170;167;169;168;166 20:05:02 INFO - PROCESS | 1947 | |13;stanford-crypto-sha256-iterative;69;67;68;68;67;68;67;67;68;67 20:05:02 INFO - PROCESS | 1947 | __end_tp_report 20:05:02 INFO - PROCESS | 1947 | __start_cc_report 20:05:02 INFO - PROCESS | 1947 | _x_x_mozilla_cycle_collect,2107 20:05:02 INFO - PROCESS | 1947 | __end_cc_report 20:05:02 INFO - PROCESS | 1947 | __startTimestamp1472612702808__endTimestamp 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | ------- Summary: start ------- 20:05:02 INFO - PROCESS | 1947 | Number of tests: 14 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#0] ai-astar Cycles:10 Average:121.40 Median:114.00 stddev:16.86 (14.8%) stddev-sans-first:17.67 20:05:02 INFO - PROCESS | 1947 | Values: 114.0 113.0 138.0 113.0 113.0 114.0 113.0 113.0 119.0 164.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:136.50 stddev:27.29 (20.0%) stddev-sans-first:28.68 20:05:02 INFO - PROCESS | 1947 | Values: 149.0 108.0 133.0 163.0 107.0 132.0 140.0 133.0 199.0 121.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:1.66 (0.9%) stddev-sans-first:1.62 20:05:02 INFO - PROCESS | 1947 | Values: 186.0 186.0 183.0 181.0 185.0 186.0 184.0 184.0 183.0 183.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#3] audio-fft Cycles:10 Average:93.70 Median:66.00 stddev:42.27 (64.0%) stddev-sans-first:44.54 20:05:02 INFO - PROCESS | 1947 | Values: 80.0 74.0 77.0 158.0 58.0 57.0 115.0 86.0 175.0 57.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:70.50 stddev:27.31 (38.7%) stddev-sans-first:28.83 20:05:02 INFO - PROCESS | 1947 | Values: 83.0 84.0 144.0 70.0 71.0 75.0 120.0 68.0 69.0 120.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.99 (2.2%) stddev-sans-first:2.06 20:05:02 INFO - PROCESS | 1947 | Values: 89.0 86.0 90.0 86.0 89.0 87.0 90.0 86.0 90.0 85.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#6] imaging-darkroom Cycles:10 Average:113.30 Median:97.00 stddev:28.34 (29.2%) stddev-sans-first:29.36 20:05:02 INFO - PROCESS | 1947 | Values: 96.0 97.0 98.0 97.0 98.0 136.0 91.0 163.0 160.0 97.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:84.50 stddev:2.96 (3.5%) stddev-sans-first:3.06 20:05:02 INFO - PROCESS | 1947 | Values: 83.0 84.0 85.0 84.0 85.0 84.0 93.0 83.0 85.0 83.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:1.00 20:05:02 INFO - PROCESS | 1947 | Values: 55.0 56.0 55.0 54.0 54.0 54.0 53.0 53.0 54.0 53.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.00 stddev:3.94 (5.5%) stddev-sans-first:3.77 20:05:02 INFO - PROCESS | 1947 | Values: 66.0 65.0 74.0 79.0 72.0 71.0 69.0 71.0 71.0 70.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.00 stddev:4.61 (5.4%) stddev-sans-first:4.88 20:05:02 INFO - PROCESS | 1947 | Values: 86.0 84.0 85.0 86.0 86.0 88.0 85.0 86.0 77.0 96.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:141.00 stddev:29.74 (21.1%) stddev-sans-first:31.31 20:05:02 INFO - PROCESS | 1947 | Values: 141.0 141.0 136.0 138.0 155.0 153.0 140.0 137.0 234.0 139.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:170.00 stddev:1.49 (0.9%) stddev-sans-first:1.54 20:05:02 INFO - PROCESS | 1947 | Values: 170.0 170.0 170.0 170.0 170.0 170.0 167.0 169.0 168.0 166.0 20:05:02 INFO - PROCESS | 1947 | 20:05:02 INFO - PROCESS | 1947 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:0.70 (1.0%) stddev-sans-first:0.53 20:05:02 INFO - PROCESS | 1947 | Values: 69.0 67.0 68.0 68.0 67.0 68.0 67.0 67.0 68.0 67.0 20:05:02 INFO - PROCESS | 1947 | -------- Summary: end -------- 20:05:02 INFO - PROCESS | 1947 | 20:05:03 INFO - TEST-INFO | 1947: exit 0 20:05:03 INFO - TEST-OK | kraken | took 59821ms 20:05:03 INFO - SUITE-END | took 1177s 20:05:03 INFO - Completed test suite (00:19:37) 20:05:03 INFO - javascript benchmark 20:05:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.076818490822, 3310.0, 3336.1326055225636, 3368.5284060231556, 3405.5913290405156, 4953.157963146269, 5512.476314750103, 5656.123300888358, 5679.233303504026, 5770.3364113127, 5665.631733937302, 5734.397888221739, 5739.827805165852, 5748.568857335696, 5756.338021127572, 5168.198929165976, 5212.478752124776, 5220.686758794484, 5230.581553475713, 5287.365516138068, 5249.448807875176, 5251.76367063483, 5255.500727430888, 5257.842264732064, 5261.473852614746, 5214.817481388152, 5227.843164705046, 5238.292830467895, 5238.4499627539135, 5251.264822924793, 5291.999999999981, 5293.947060529384, 5299.758291397573, 5320.388155362144, 5328.866778330534, 5033.219850923077, 5059.165237735806, 5145.974270128663, 5247.107991641392, 5256.789728410859, 4621.976890115561, 5649.830505084854, 5683.886322273532, 5698.914516282218, 5780.797672081457, 6231.626102433868, 6238.2825975013, 6249.218847644044, 6251.718672659741, 6253.6873156342, 6131.96934015327, 6188.381161883821, 6238.9376106238815, 6276.941392395654, 6331.525135614847, 5818.185454036354, 5824.271966004249, 5830.7959221427045, 5846.239988801429, 5876.089206173007, 6238.625682459067, 6246.906296405557, 6255.749770009149, 6256.624602523863, 6277.246730392382, 5953.702314884238, 5958.553108516879, 6042.456178943916, 6136.447719705248, 6173.351798061139, 6158.3225845157895, 6196.504279657616, 6229.844253893689, 6237.781677641261, 6303.117563541108, 6234.0, 6241.0, 6257.906131408033, 6261.0, 6265.154204182468, 6113.296970848321, 6179.474744646755, 6266.811995640138, 6269.968650156811, 6275.748970041239, 4852.0, 4868.0, 4934.988327392876, 4944.307796908436, 4990.650654454226, 4430.911381772411, 4443.311286750559, 4447.488538818077, 4458.152950939311, 4494.393256910341, 5750.338711048199, 5769.769209231584, 5772.624779389326, 5783.508401785895, 5794.449527294901, 6126.517673797367, 6136.610985192662, 6188.350223226586, 6246.875062498815, 6275.309715931244, 5681.34664513578, 5720.170575266647, 5736.770529178786, 5740.483356497935, 5774.249347584871, 5361.0618141825025, 5365.1147560652835, 5365.919511207335, 5372.516473517402, 5385.057614917374, 5206.297149884717, 6212.937870621417, 6259.812205633838, 6282.403171698681, 6309.337519560564, 6280.1207830902795, 6281.560290779785, 6297.716602752796, 6299.842503937255, 6310.116583678197, 6291.685415729286, 6298.307186209422, 6315.873682526232, 6316.084167795552, 6356.364363563606, 6070.180525628984, 6115.727150604437, 6138.3883983858805, 6253.812385628438, 6253.937460625518, 6108.633481991094, 6243.937560624336, 6275.058741188858, 6284.685765711604, 6302.338254483213, 6119.632822030692, 6129.724162412791, 6153.323134455117, 6153.507719382349, 6159.168512250968, 5867.0, 6082.908756368746, 6112.388761123852, 6137.3248942617165, 6154.661493617715, 6024.789132380346, 6035.426634469718, 6052.061930400619, 6072.271327440559, 6079.513638908788, 6103.515139462075, 6113.345812258461, 6145.033589251575, 6145.539084568764, 6153.723082461211, 5863.648181109147, 5899.203607512931, 5939.4951429129005, 5958.970205148947, 6035.637861728311], "value": 5698.444596920263, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.57317986991563, 482.1802935010483, 500.589516596391, 508.02712804978444, 546.5955193157065, 30677.07968760938, 32046.519302210458, 39401.0, 40338.0, 40436.79781601095, 42333.57666423335, 42644.14711705764, 42669.573304266945, 42692.0, 42738.14523709532, 9688.128068473854, 9865.358751681153, 9865.457399843006, 9896.604135834557, 9897.208223342152, 9720.562574684122, 9820.852687209697, 9831.705048848511, 9849.310548261625, 9873.60505579776, 9802.36284641496, 9850.261230407712, 9855.753606159868, 9875.55559999798, 9887.703368898909, 8369.665213391458, 9777.31558790885, 9811.65659201928, 9842.852357214648, 9910.653127140515, 9690.079442452956, 9760.609575617038, 9767.706968790877, 9772.95113524435, 9791.804163916682, 40035.19929601392, 40394.79802600968, 40428.0, 40435.797821011, 40444.393334100016, 3339.916502087443, 3369.0, 3537.168765340143, 3713.424419215026, 3808.295465338921, 29862.70137298621, 30035.098947031624, 30442.086737397913, 30623.387532249453, 30750.53874191889, 3149.3228955774616, 3161.525771134325, 3210.7110360067704, 3222.130024893289, 3245.221146924721, 473903.0, 474173.2582674164, 476285.0, 478010.0, 487509.0, 55559.44440555543, 55817.720911396, 55846.0, 55889.7205513978, 56191.0, 32637.347253054806, 33151.66848331534, 58975.0, 59262.0, 59278.0, 16177.029378237108, 16692.499225023268, 16742.58143546421, 16762.916185418995, 16785.076820774975, 12676.23942563449, 12729.172603780724, 12739.044571657163, 12746.936265318614, 12785.360731963365, 18652.0, 18658.0, 18665.90667046656, 18669.346572869883, 18700.53248668767, 6520.739170433224, 7434.36807871337, 7558.962205188939, 7610.771676849703, 7670.079590449071, 833.9207775261341, 911.4531281231341, 926.4528237842819, 946.8011717539256, 966.2270183852889, 22693.546129077655, 24463.0, 30054.398912021636, 30437.69562304393, 30582.388352232832, 1016.8322226832638, 1033.1115240892825, 1067.4716015572342, 1095.780843831221, 1126.7633747012173, 12295.077869159848, 15440.691186176438, 15560.766588501181, 15687.058776473448, 16037.759433608506, 654789.0, 657122.0, 659203.0, 669877.6506117438, 672860.0, 57047.71476142676, 57639.0, 57652.42347576471, 57775.555611109216, 58493.70753146292, 1213.7329787446924, 1342.5166939901628, 1351.905366624327, 1356.8371795384617, 1382.3019375215451, 29732.702672972995, 31330.373392531565, 31448.685513144577, 31588.842055789573, 32393.676063239065, 49348.2597761041, 50236.0, 50243.99512009666, 50244.74877625588, 50421.74789126031, 560131.0, 566068.0, 566219.0, 570442.8867133993, 571167.0, 35307.47038794471, 35424.46863297001, 42686.35970460508, 42834.0, 44309.0, 46577.068458631315, 46802.0, 47025.0, 47343.763281183376, 47930.80172995703, 35586.64413355937, 36798.0, 38430.6156938427, 39051.41422878602, 40655.59344406518, 37590.43614345732, 38424.2315153701, 39188.216235674554, 39787.602123979545, 39924.00189995273, 37837.43243851289, 38268.425973610974, 38575.42136867893, 38901.61098389093, 38969.610303896596, 30536.541951870295, 32024.359512809147, 34205.0, 34511.13722156866, 34947.30105397827, 22844.85775711241, 25106.24681259565, 25638.0, 26203.082892098686, 26844.0], "value": 19938.470870962192, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.7449359342608, 330.7023678689179, 345.3145506170253, 377.5978582809308, 388.25455126157794, 15289.082655040706, 16038.599035024115, 19209.75136616119, 20584.485387865312, 20995.685064724003, 17090.401835935743, 19842.908640024794, 20667.96660166993, 20893.164273429084, 21166.153353865866, 5805.7967971121125, 6120.112583675386, 6134.539909506782, 6136.938630613704, 6139.079138129294, 4193.35003074524, 5093.490650934914, 6048.879022419571, 6100.267967843843, 6110.572259941806, 4406.911861762763, 5574.9721251393885, 6325.5255855811, 6351.364863513658, 6671.199456065279, 6379.0, 6516.739330426777, 6601.801945941582, 6686.331366863324, 6693.2302785179845, 5670.121131224626, 5678.801241956512, 5680.744366503517, 5688.061469857468, 5696.857578560528, 24753.25740227796, 25650.871745641154, 25748.227553173434, 25881.870590647115, 26120.34699132518, 2183.912643494258, 2192.365957235447, 2233.9776602233933, 2400.1599440195964, 2895.1604034829875, 14654.560363189015, 15109.848901511064, 15298.388064477407, 15362.692746145014, 15518.680912122483, 1791.8835275707038, 1818.8090250523637, 1888.2352647177916, 1890.3779267366906, 1895.0714150066506, 84020.99999999939, 104362.47818760858, 106166.0, 107027.46486267597, 107172.39241411386, 10494.632687856041, 10625.362478251329, 10764.515596798163, 10765.461726913623, 10829.67510974672, 13009.934950325187, 13047.543335983195, 13062.085654004128, 13064.673383165496, 13070.934645326903, 8235.83528329442, 8413.116622754646, 8413.95793021031, 8418.957905210435, 8561.700340488052, 8464.365172612079, 8482.872756908651, 8639.481631102153, 8682.131786821268, 8698.956505217433, 6254.405831446005, 6355.0785136155, 6366.140571022853, 6367.299597044322, 6384.808455746335, 1574.6457047164297, 1772.9468115956543, 1799.2892807341027, 1810.357323150289, 1812.8187181281767, 708.4863473981322, 943.3632298198826, 946.5645802930592, 965.5365424596092, 1067.199600299775, 21060.05229764664, 21152.259670911444, 22203.77796222017, 22290.665640015413, 22340.776592234193, 955.4918339359182, 956.9951550871557, 975.5512464266375, 1003.2074661017714, 1027.1833892055834, 8938.776530586658, 10309.793804123876, 11006.944965275123, 11075.833862492069, 11169.0, 566521.0, 568588.0, 578940.0, 581341.1865881287, 583843.0, 136300.0, 137859.0, 139151.0, 141392.0, 143281.28359358138, 975.1175186456127, 976.5410257179115, 1007.4459047523833, 1007.7682133109279, 1016.6238491757949, 18455.89264644072, 18675.43973680792, 18697.25210991545, 18698.34555790541, 18709.25162993465, 51730.48269517407, 52023.0, 52607.73696131495, 53545.46454535405, 53838.73080634572, 74921.12697182615, 75140.49719005698, 76790.61604692164, 76793.84809227978, 77739.0, 7941.8411631768195, 7955.204479551999, 8172.141925098017, 8480.15198480147, 8490.0, 11688.590899318484, 12163.635090947286, 12525.37373131358, 12599.18105323151, 13220.272884991418, 10817.729556761144, 11080.722981925517, 11318.660440186808, 11328.32030078165, 11391.601293954673, 10762.892371076188, 10933.289336193122, 11115.0, 11238.494267758135, 11522.251053681484, 9631.951840240754, 10404.843927341248, 10895.836562451728, 11322.853255742466, 11502.654920352403, 7943.563104029334, 8023.438359314792, 8239.95880020596, 8250.46371985819, 8410.789730256547, 8358.205970432798, 10560.366378017343, 10670.253082284476, 11224.887751122384, 11385.658430247106], "value": 9640.038738192728, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.401839264294, 1524.108396587996, 1707.906065166416, 1824.8175182481746, 1900.9239630414784, 8489.702860399888, 9025.323100767437, 9032.638694452231, 9033.187013168814, 9034.954825225881, 7814.452988290822, 8053.275205231528, 8567.0, 8763.430377025488, 8806.383553151281, 2374.821888358376, 2411.4815314707334, 2414.28778510339, 2417.9274621761374, 2423.1882319423016, 2011.959760804783, 2391.175044609607, 2405.374602603327, 2406.133791834947, 2410.8553486790847, 2372.540887354282, 2396.7363590005084, 2407.7712617301327, 2414.5050264695697, 2415.915442959497, 2371.739108698042, 2375.5367703297866, 2387.057112440585, 2388.7850093491497, 2409.74697656747, 2364.9054037838464, 2379.250536081146, 2390.653355263477, 2390.9402264943515, 2399.9160029398886, 8891.4665120093, 8893.4663920165, 8951.41815781972, 8955.149260820212, 8987.595558199897, 5223.764930578133, 5381.704006279653, 5382.111951527993, 5390.353157621072, 5397.919031214535, 3493.056874643832, 3498.860045598173, 3572.1962558424407, 3572.7856328620364, 3574.303010912873, 938.8874184091873, 1039.3763741754963, 1043.979120417595, 1051.285126114242, 1062.378508572486, 11321.547138114465, 11640.417979101096, 11794.35131067791, 11810.822837657528, 11843.822342664867, 4584.61030812378, 4643.0, 4777.092352453023, 4785.1625965455905, 4834.468208497063, 9987.700368988797, 10036.197104231644, 10057.64798232073, 10066.547005384775, 10094.09153176203, 5346.732663366816, 5348.384935732441, 5351.384590772033, 5355.86610334737, 5376.4085950545405, 4360.738355698668, 4516.164509565675, 4719.10337035962, 4830.8309209178215, 4878.219449900832, 3860.034991252187, 3902.863399780994, 4012.337964235868, 4024.054347228399, 4082.9183416332103, 1290.393515047926, 1294.1717300927414, 1296.7665820152274, 1308.8167656528094, 1309.8362704661918, 754.5849782619538, 844.2612713875359, 884.6859364925488, 889.4840992224495, 927.4759760735163, 5393.6763794172475, 5636.379998200195, 5672.7730890763905, 5904.852378690567, 6086.513078953673, 927.5408672707053, 927.6567669962158, 943.7027336389016, 945.6784693204344, 988.0662773678948, 3069.1713237426216, 3887.1642596841816, 3914.256291304643, 3920.09837737322, 4024.0342317844093, 10614.787704246026, 10835.67492975212, 10958.178136639784, 10964.780704385868, 10979.0, 10902.073323767569, 10908.836367454494, 10917.727056823644, 10935.671929842118, 10984.121270298358, 8124.431289809657, 8268.958655206805, 8271.379646526535, 8279.54462504568, 8282.58587070644, 5338.225957236102, 5427.674339539641, 5709.9714501427225, 5843.0, 5951.1073338999495, 9983.400995940267, 10201.0, 10227.488625568543, 10248.64129755455, 10259.076683098558, 9633.036696330311, 10136.239782016231, 10174.74563135898, 10315.48422578853, 10345.482725863827, 6893.758718445031, 7152.320529549685, 7380.815479613053, 7458.627068646654, 7659.157492675913, 8414.663413463393, 8453.492790432594, 8580.485170889766, 8584.613692383982, 8614.82770344602, 7350.632468376452, 7441.330280274801, 7516.0, 7600.391968642606, 7700.884486732815, 6029.939700602938, 7220.1696804866015, 7333.303336183054, 7415.406767458482, 7501.699932002659, 6932.098827152639, 6964.338387853146, 7017.087778588751, 7032.718691252498, 7283.980242766123, 4929.334539837076, 4975.452700203047, 5001.999600080071, 5040.949590504048, 5146.485351464824, 4893.045856058035, 5053.823116190915, 5079.77141028662, 5120.18077107661, 5227.424983251915], "value": 4600.680112956464, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.28099078743264, 300.14458792441536, 308.92122508760264, 311.4782738912321, 312.7966821565982, 4741.620670346372, 5209.583233341333, 5354.598405119612, 5383.950129724705, 5396.865078373043, 4283.828646854122, 4948.876278093049, 5134.127198376275, 5251.5798736101115, 5334.359876814778, 4073.4093556434373, 4087.1825634872994, 4094.61101195386, 4105.733127346728, 4113.156802855422, 4063.349864021757, 4088.2641124597562, 4102.79486025699, 4102.938455923164, 4118.8970275743195, 3745.681617062553, 3910.608939106095, 3968.7420317679403, 4025.778582177979, 4047.7166598338126, 4002.5397079335958, 4004.6796256299426, 4021.658159056484, 4048.1701251243426, 4057.594240575963, 2911.3595009097967, 3432.7768695034742, 3432.8111953842526, 3452.2922800825772, 3462.394081035834, 4180.519240287375, 4884.853454396374, 5042.621803364726, 5120.615953803443, 5135.8202462914, 3769.8492060317553, 4363.694541382105, 4522.479914809774, 4542.341360502709, 4558.222293305614, 5496.7526461309335, 5559.555235581184, 5573.972130139364, 5583.162525621149, 5589.692566908819, 3373.561437013197, 3719.0516418313427, 3730.9253814923823, 3746.7189960753053, 3753.6997040236715, 5444.727763611843, 5666.4050274721385, 5706.258186435778, 5773.5958482906635, 5801.694618710799, 3782.962170378316, 3797.9175934858986, 3804.0, 3854.9376083795037, 3857.4792403025795, 3915.804209789499, 3925.7448265862627, 3939.783311917872, 3961.286968345668, 3965.7111438782513, 3532.8410221540094, 3621.927561448809, 3969.7221194516683, 4033.3748269018347, 4482.686211965196, 5469.261649677323, 5519.254900588449, 5559.58303127259, 5564.165375193672, 5576.33084029919, 1242.6023672424737, 1310.7509573181255, 1328.4752522753458, 1331.2145833958011, 1342.64419928717, 699.5487910297829, 745.5042396806064, 833.3166803221301, 890.3322508118911, 890.7796319042823, 4692.976535117303, 4698.788554515055, 5062.316587260672, 5064.873378165502, 5198.454162312978, 4496.212348344047, 4814.542618451242, 5032.043911656773, 5519.31008623922, 5543.501084902378, 4220.53574106848, 4967.279744437036, 5064.392272927273, 5084.9745751271, 5087.9824035193105, 2217.9001944912516, 2743.2181828178873, 2897.3625802323454, 2910.767138628905, 2930.956035659467, 5500.119980803132, 5760.596758226968, 5765.625234359753, 5784.942150578525, 5788.623739456922, 5359.383670877821, 5661.773529058792, 5693.28833895763, 5711.828645140652, 5765.3946335634155, 4622.352870598119, 5663.037283661787, 5737.454941780607, 5795.130730390475, 5824.912626310694, 4757.024809914029, 5207.453217412117, 5566.608347913022, 5577.693226872561, 5623.662580245198, 4778.856634300976, 4832.540908613676, 5208.296879921162, 5310.707911064928, 5583.692896890548, 5262.842114736564, 5430.429804870431, 5659.943400566106, 6016.157737916607, 6021.7591296347655, 3224.790388624732, 3287.964291248249, 3418.4530475124857, 3806.9428958566195, 3845.8846234613006, 4570.520095389937, 4674.088552732321, 4696.8590942271785, 4796.37647105874, 5628.971855140698, 5341.679499230058, 5519.061759500974, 5521.503064724194, 5557.527610153021, 5616.550675945833, 3995.141044675409, 4921.990991846591, 5318.494742999409, 5399.379071406682, 5552.58355623338, 4583.862484125481, 5244.659097158673, 5538.889222215614, 5556.416576259435, 5560.443955604407, 5225.0, 5360.65155764874, 5555.50000499957, 5687.289088863892, 5688.146777983336, 4445.755483448312, 4678.017616300615, 5472.823342981278, 5478.753456094565, 5505.862353441035], "value": 3982.4338316842022, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.2512547255084, 696.7909627111867, 725.648060690565, 761.0828951113908, 803.8995125609299, 15406.92296538517, 15682.921585392085, 16004.279807408666, 16007.519774406772, 16011.199440028011, 13784.104033237832, 13864.722705545882, 13871.375788089535, 13924.651883702913, 14298.0, 663.051835874699, 683.9110915580967, 687.3092541995288, 688.1157712339644, 825.1418524734285, 681.6523572977792, 685.6811582614083, 813.9552324622143, 839.6137776622761, 842.4987132656059, 873.7422460374173, 881.1188811188811, 889.6619284671816, 889.7330800759779, 891.9152680495375, 809.7975506123469, 811.6225954930926, 875.5490922175085, 878.2359347367831, 880.0978996528578, 527.5304978569077, 542.1055258822935, 656.2092678322609, 657.1030543308398, 658.7430901948244, 12275.631731048083, 12342.629721108291, 12343.814842777365, 14145.0, 14711.190884501348, 14241.85758142426, 14762.778558321634, 14846.331915063847, 14853.183074930874, 14899.404023839033, 11800.527978880835, 11860.169788114836, 11879.40602969848, 11960.282383057045, 12302.200356976768, 3823.808809559511, 3886.125621735087, 3906.8241929112974, 3930.5479869815053, 3947.4473573699706, 117881.41059294649, 117998.41000794941, 118897.81102188955, 119153.40423297828, 119861.40069299685, 9782.9021709782, 11002.669919902415, 11205.831912521318, 11271.436428178558, 11721.4139293035, 20490.692639610417, 21092.05085771144, 23163.0, 23180.18869339565, 23214.419639509146, 7821.882671759929, 7881.881771773428, 7984.960075199587, 7991.560464174526, 8072.112067672551, 3654.3422184007136, 4353.0, 4358.128374325148, 4366.890827729332, 4513.413256276729, 3448.4827275908815, 3574.4638304254568, 3583.623719509414, 3586.49367265749, 3588.4796704477703, 649.782322921817, 661.5997321620447, 668.9531732778756, 672.1933679584519, 674.3425160468602, 575.9107338362561, 605.7819185093363, 667.3593350383686, 667.951316433205, 673.7271405080852, 8735.781605459915, 9057.18485336323, 9229.353945223904, 9352.579133938989, 9463.0, 574.7729646789495, 622.9501639868799, 646.3697894552868, 658.5686375424104, 679.5854528737466, 3908.6677632400997, 4206.768627725504, 4332.415123958288, 4521.11838191556, 4575.37821068249, 99297.0, 118392.34468897841, 130515.0, 159423.0, 159659.0, 134694.0, 136508.31745841407, 139309.3034534841, 140204.0, 143892.0, 96128.51935740277, 96726.51636741911, 116239.41880290545, 116671.0, 117755.41122294334, 9512.476813775309, 9853.950730246303, 9866.309358345134, 9905.0, 9976.15202707778, 59679.0, 60584.394156059636, 60705.78588428295, 61671.6916415415, 62117.689411554464, 35864.82067589645, 59199.40800591939, 72642.27357726566, 89617.0, 94041.0, 12981.675458113623, 13189.604311870658, 13311.267880266678, 13330.0, 13546.86453135456, 14890.627734306729, 14911.25443727831, 15262.923685381502, 15346.232688365313, 15414.922925385301, 13727.382267798173, 13826.516071937434, 14015.0, 14038.78941815853, 14657.0, 13664.316784160554, 13840.792388113985, 13935.790963135358, 13956.371963261474, 14274.571762847132, 13452.730945381234, 13557.0, 13607.319634018457, 13733.176009439465, 13941.651458713613, 11016.779664406507, 11457.19799614019, 11654.592089277174, 11700.6653103865, 11907.821382679094, 9906.356086854332, 9943.651972180938, 11281.71795705114, 11426.2001659883, 11568.942155289169], "value": 7688.087526001597, "unit": "score"}], "name": "dromaeo_css", "value": 7323.6431621869415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 138.0, 113.0, 113.0, 114.0, 113.0, 113.0, 119.0, 164.0], "value": 121.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 108.0, 133.0, 163.0, 107.0, 132.0, 140.0, 133.0, 199.0, 121.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 183.0, 181.0, 185.0, 186.0, 184.0, 184.0, 183.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 77.0, 158.0, 58.0, 57.0, 115.0, 86.0, 175.0, 57.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 144.0, 70.0, 71.0, 75.0, 120.0, 68.0, 69.0, 120.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 89.0, 87.0, 90.0, 86.0, 90.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 97.0, 98.0, 136.0, 91.0, 163.0, 160.0, 97.0], "value": 113.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 85.0, 84.0, 85.0, 84.0, 93.0, 83.0, 85.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 54.0, 54.0, 54.0, 53.0, 53.0, 54.0, 53.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 74.0, 79.0, 72.0, 71.0, 69.0, 71.0, 71.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 86.0, 86.0, 88.0, 85.0, 86.0, 77.0, 96.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 136.0, 138.0, 155.0, 153.0, 140.0, 137.0, 234.0, 139.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 170.0, 170.0, 170.0, 170.0, 167.0, 169.0, 168.0, 166.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 68.0, 68.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1512.9, "alertThreshold": 2.0}]} 20:05:04 INFO - Return code: 0 20:05:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:05:04 INFO - # TBPL SUCCESS # 20:05:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:05:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:05:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:05:04 INFO - 2016-08-30 20:05:04.137 screenresolution[1951:21482] starting screenresolution argv=screenresolution get 20:05:04 INFO - 2016-08-30 20:05:04.140 screenresolution[1951:21482] Display 0: 1600x1200x32@60 20:05:04 INFO - 2016-08-30 20:05:04.146 screenresolution[1952:21485] starting screenresolution argv=screenresolution list 20:05:04 INFO - Available Modes on Display 0 20:05:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:05:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:05:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:05:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:05:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:05:04 INFO - Intel Iris: 20:05:04 INFO - Chipset Model: Intel Iris 20:05:04 INFO - Type: GPU 20:05:04 INFO - Bus: Built-In 20:05:04 INFO - VRAM (Dynamic, Max): 1536 MB 20:05:04 INFO - Vendor: Intel (0x8086) 20:05:04 INFO - Device ID: 0x0a2e 20:05:04 INFO - Revision ID: 0x0009 20:05:04 INFO - Displays: 20:05:04 INFO - Display: 20:05:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:05:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:05:04 INFO - Display Serial Number: 1600x1200 60 20:05:04 INFO - Main Display: Yes 20:05:04 INFO - Mirror: Off 20:05:04 INFO - Online: Yes 20:05:04 INFO - Rotation: Supported 20:05:04 INFO - Return code: 0 20:05:04 INFO - Running post-action listener: _resource_record_post_action 20:05:04 INFO - [mozharness: 2016-08-31 03:05:04.382910Z] Finished run-tests step (success) 20:05:04 INFO - Running post-run listener: _resource_record_post_run 20:05:04 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 118784; Write bytes: 700896256; Read time: 3; Write time: 1873 20:05:04 INFO - TinderboxPrint: CPU usage
53.5% 20:05:04 INFO - TinderboxPrint: I/O read bytes / time
118,784 / 3 20:05:04 INFO - TinderboxPrint: I/O write bytes / time
700,896,256 / 1,873 20:05:04 INFO - TinderboxPrint: CPU idle
2,256.7 (46.5%) 20:05:04 INFO - TinderboxPrint: CPU system
967.2 (19.9%) 20:05:04 INFO - TinderboxPrint: CPU user
1,625.8 (33.5%) 20:05:04 INFO - TinderboxPrint: Swap in / out
405,417,984 / 0 20:05:04 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 398533120; Write bytes: 392390144; Read time: 29427; Write time: 713 20:05:04 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 118784; Write bytes: 291917312; Read time: 3; Write time: 1114 20:05:04 INFO - Running post-run listener: _upload_blobber_files 20:05:04 INFO - Blob upload gear active. 20:05:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:05:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:05:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:05:05 INFO - (blobuploader) - INFO - Open directory for files ... 20:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:05:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:05:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:05:06 INFO - (blobuploader) - INFO - Done attempting. 20:05:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:05:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:05:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:05:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:05:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:05:07 INFO - (blobuploader) - INFO - Done attempting. 20:05:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:05:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:05:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:05:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:05:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:05:09 INFO - (blobuploader) - INFO - Done attempting. 20:05:09 INFO - (blobuploader) - INFO - Iteration through files over. 20:05:09 INFO - Return code: 0 20:05:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:05:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:05:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224e12252eadc40ab2da32b28c1b90b376fadac74e89e39ff7312b723e7cf4fb86af753eda5e26c3c6b9fb4563a7d62361dbac5f3c343d0a6efc89e18878ccbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bd68c2c80b94c376459e757701c2ff52cddf879a407df6f618eb1087dc90082c4b056da484f0db900794668228d8cde25ac36b8a02fb436b6989f59a0d96a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edf5121efba0e678d6d799d24e3f91fa2806ea13539189b9d2758dfc5bed5f62fff3f14bc3113e77094063e46e1a9250127cdab66cdd3378accec11bf92991dd"} 20:05:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:05:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:05:09 INFO - Contents: 20:05:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224e12252eadc40ab2da32b28c1b90b376fadac74e89e39ff7312b723e7cf4fb86af753eda5e26c3c6b9fb4563a7d62361dbac5f3c343d0a6efc89e18878ccbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bd68c2c80b94c376459e757701c2ff52cddf879a407df6f618eb1087dc90082c4b056da484f0db900794668228d8cde25ac36b8a02fb436b6989f59a0d96a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edf5121efba0e678d6d799d24e3f91fa2806ea13539189b9d2758dfc5bed5f62fff3f14bc3113e77094063e46e1a9250127cdab66cdd3378accec11bf92991dd"} 20:05:09 INFO - Running post-run listener: copy_logs_to_upload_dir 20:05:09 INFO - Copying logs to upload dir... 20:05:09 INFO - mkdir: /builds/slave/test/build/upload/logs 20:05:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.742251 ========= master_lag: 0.82 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-30 20:05:10.287122) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 17 secs) (at 2016-08-30 20:05:10.290773) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U17ndrUzbr/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224e12252eadc40ab2da32b28c1b90b376fadac74e89e39ff7312b723e7cf4fb86af753eda5e26c3c6b9fb4563a7d62361dbac5f3c343d0a6efc89e18878ccbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bd68c2c80b94c376459e757701c2ff52cddf879a407df6f618eb1087dc90082c4b056da484f0db900794668228d8cde25ac36b8a02fb436b6989f59a0d96a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edf5121efba0e678d6d799d24e3f91fa2806ea13539189b9d2758dfc5bed5f62fff3f14bc3113e77094063e46e1a9250127cdab66cdd3378accec11bf92991dd"} build_url:https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010984 build_url: 'https://queue.taskcluster.net/v1/task/AbVxyZ4fR_ODYZtftAdmZA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/224e12252eadc40ab2da32b28c1b90b376fadac74e89e39ff7312b723e7cf4fb86af753eda5e26c3c6b9fb4563a7d62361dbac5f3c343d0a6efc89e18878ccbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/30bd68c2c80b94c376459e757701c2ff52cddf879a407df6f618eb1087dc90082c4b056da484f0db900794668228d8cde25ac36b8a02fb436b6989f59a0d96a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edf5121efba0e678d6d799d24e3f91fa2806ea13539189b9d2758dfc5bed5f62fff3f14bc3113e77094063e46e1a9250127cdab66cdd3378accec11bf92991dd"}' ========= master_lag: 17.40 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 17 secs) (at 2016-08-30 20:05:27.701581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-08-30 20:05:27.701892) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U17ndrUzbr/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.Hies0GkQtK/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.006031 ========= master_lag: 6.31 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 6 secs) (at 2016-08-30 20:05:34.015642) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-08-30 20:05:34.015960) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-08-30 20:05:35.351071) ========= ========= Total master_lag: 24.61 =========