builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0025 starttime: 1473230767.09 results: success (0) buildid: 20160906185516 builduid: f5f255877a8443dbbc841936a6d98819 revision: a6b6a93eb41a05e310a11f0172f01ba9b21d3eac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.091378) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.091773) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.092040) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.117867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.118153) ========= 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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-09-06 23:46:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-09-06 23:46:07 (143 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.174660 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.342550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.342901) ========= 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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.028272 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:07.403397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 23:46:07.403732) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a6b6a93eb41a05e310a11f0172f01ba9b21d3eac --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a6b6a93eb41a05e310a11f0172f01ba9b21d3eac --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SCeDlpK3Z0/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.hug78Uxt7s/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-09-06 23:46:07,487 truncating revision to first 12 chars 2016-09-06 23:46:07,487 Setting DEBUG logging. 2016-09-06 23:46:07,487 attempt 1/10 2016-09-06 23:46:07,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a6b6a93eb41a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 23:46:08,361 unpacking tar archive at: fx-team-a6b6a93eb41a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.118922 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 23:46:08.551000) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:08.551284) ========= script_repo_revision: a6b6a93eb41a05e310a11f0172f01ba9b21d3eac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:08.551653) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:08.551913) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 23:46:08.572305) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-06 23:46:08.572649) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SCeDlpK3Z0/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.hug78Uxt7s/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:08 INFO - MultiFileLogger online at 20160906 23:46:08 in /builds/slave/test 23:46:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:46:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:46:08 INFO - {'append_to_log': False, 23:46:08 INFO - 'base_work_dir': '/builds/slave/test', 23:46:08 INFO - 'blob_upload_branch': 'Fx-Team', 23:46:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:46:08 INFO - 'branch': 'Fx-Team', 23:46:08 INFO - 'buildbot_json_path': 'buildprops.json', 23:46:08 INFO - 'config_files': ('talos/mac_config.py',), 23:46:08 INFO - 'default_actions': ('clobber', 23:46:08 INFO - 'read-buildbot-config', 23:46:08 INFO - 'download-and-extract', 23:46:08 INFO - 'populate-webroot', 23:46:08 INFO - 'create-virtualenv', 23:46:08 INFO - 'install', 23:46:08 INFO - 'run-tests'), 23:46:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:46:08 INFO - 'download_minidump_stackwalk': True, 23:46:08 INFO - 'download_symbols': 'ondemand', 23:46:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:46:08 INFO - 'tooltool.py': '/tools/tooltool.py', 23:46:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:46:08 INFO - '/tools/misc-python/virtualenv.py')}, 23:46:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:46:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:46:08 INFO - 'installer_path': 'installer.exe', 23:46:08 INFO - 'log_level': 'info', 23:46:08 INFO - 'log_name': 'talos', 23:46:08 INFO - 'log_to_console': True, 23:46:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:46:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:46:08 INFO - 'opt_config_files': (), 23:46:08 INFO - 'pip_index': False, 23:46:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:46:08 INFO - 'cmd': ('bash', 23:46:08 INFO - '-c', 23:46:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:46:08 INFO - 'enabled': True, 23:46:08 INFO - 'halt_on_failure': False, 23:46:08 INFO - 'name': 'check_screen_resolution'},), 23:46:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:46:08 INFO - 'cmd': ('bash', 23:46:08 INFO - '-c', 23:46:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:46:08 INFO - 'enabled': True, 23:46:08 INFO - 'halt_on_failure': False, 23:46:08 INFO - 'name': 'check_screen_resolution'},), 23:46:08 INFO - 'run_cmd_checks_enabled': True, 23:46:08 INFO - 'sps_profile': False, 23:46:08 INFO - 'sps_profile_interval': 0, 23:46:08 INFO - 'suite': 'dromaeojs-e10s', 23:46:08 INFO - 'system_bits': '32', 23:46:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:46:08 INFO - 'title': 't-yosemite-r7-0025', 23:46:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:46:08 INFO - 'use_talos_json': True, 23:46:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:46:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:46:08 INFO - 'work_dir': 'build'} 23:46:08 INFO - [mozharness: 2016-09-07 06:46:08.767482Z] Running clobber step. 23:46:08 INFO - Running pre-action listener: _resource_record_pre_action 23:46:08 INFO - Running main action method: clobber 23:46:08 INFO - rmtree: /builds/slave/test/build 23:46:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:46:11 INFO - Running post-action listener: _resource_record_post_action 23:46:11 INFO - [mozharness: 2016-09-07 06:46:11.297076Z] Finished clobber step (success) 23:46:11 INFO - [mozharness: 2016-09-07 06:46:11.297241Z] Running read-buildbot-config step. 23:46:11 INFO - Running pre-action listener: _resource_record_pre_action 23:46:11 INFO - Running main action method: read_buildbot_config 23:46:11 INFO - Using buildbot properties: 23:46:11 INFO - { 23:46:11 INFO - "project": "", 23:46:11 INFO - "product": "firefox", 23:46:11 INFO - "script_repo_revision": "production", 23:46:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 23:46:11 INFO - "repository": "", 23:46:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 23:46:11 INFO - "buildid": "20160906185516", 23:46:11 INFO - "pgo_build": "False", 23:46:11 INFO - "basedir": "/builds/slave/test", 23:46:11 INFO - "buildnumber": 780, 23:46:11 INFO - "slavename": "t-yosemite-r7-0025", 23:46:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 23:46:11 INFO - "platform": "yosemite_r7", 23:46:11 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 23:46:11 INFO - "branch": "fx-team", 23:46:11 INFO - "repo_path": "integration/fx-team", 23:46:11 INFO - "revision": "a6b6a93eb41a05e310a11f0172f01ba9b21d3eac", 23:46:11 INFO - "stage_platform": "macosx64", 23:46:11 INFO - "builduid": "f5f255877a8443dbbc841936a6d98819", 23:46:11 INFO - "slavebuilddir": "test" 23:46:11 INFO - } 23:46:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 23:46:11 INFO - Running post-action listener: _resource_record_post_action 23:46:11 INFO - [mozharness: 2016-09-07 06:46:11.300592Z] Finished read-buildbot-config step (success) 23:46:11 INFO - [mozharness: 2016-09-07 06:46:11.300740Z] Running download-and-extract step. 23:46:11 INFO - Running pre-action listener: _resource_record_pre_action 23:46:11 INFO - Running main action method: download_and_extract 23:46:11 INFO - mkdir: /builds/slave/test/build/tests 23:46:11 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')]} 23:46:11 INFO - trying https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 23:46:11 INFO - Downloading https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/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 23:46:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/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 23:46:11 INFO - Downloaded 1199 bytes. 23:46:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 23:46:11 INFO - Using the following test package requirements: 23:46:11 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:46:11 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 23:46:11 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 23:46:11 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'jsshell-mac.zip'], 23:46:11 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 23:46:11 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 23:46:11 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 23:46:11 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 23:46:11 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 23:46:11 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 23:46:11 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 23:46:11 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:46:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:46:12 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 23:46:19 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 23:46:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 23:46:20 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 23:46:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 23:46:27 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 23:46:28 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')]} 23:46:28 INFO - trying https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:46:28 INFO - Downloading https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 23:46:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/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 23:46:33 INFO - Downloaded 111495597 bytes. 23:46:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:46:33 INFO - mkdir: /builds/slave/test/properties 23:46:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:46:33 INFO - Writing to file /builds/slave/test/properties/build_url 23:46:33 INFO - Contents: 23:46:33 INFO - build_url:https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 23:46:33 INFO - Running post-action listener: _resource_record_post_action 23:46:33 INFO - Running post-action listener: set_extra_try_arguments 23:46:33 INFO - [mozharness: 2016-09-07 06:46:33.820873Z] Finished download-and-extract step (success) 23:46:33 INFO - [mozharness: 2016-09-07 06:46:33.821010Z] Running populate-webroot step. 23:46:33 INFO - Running pre-action listener: _resource_record_pre_action 23:46:33 INFO - Running main action method: populate_webroot 23:46:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:46:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:46:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:46:33 INFO - '--tppagecycles', 23:46:33 INFO - '7'], 23:46:33 INFO - 'tests': ['tsvgm']}}, 23:46:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:46:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:46:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:46:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:46:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:46:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:46:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['damp', 'tps']}, 23:46:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'tests': ['damp', 'tps']}, 23:46:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['dromaeo_dom']}, 23:46:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:46:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['basic_compositor_video']}, 23:46:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:46:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['a11yr', 23:46:33 INFO - 'ts_paint', 23:46:33 INFO - 'tpaint', 23:46:33 INFO - 'sessionrestore', 23:46:33 INFO - 'sessionrestore_no_auto_restore', 23:46:33 INFO - 'tabpaint']}, 23:46:33 INFO - 'other-e10s': {'tests': ['a11yr', 23:46:33 INFO - 'ts_paint', 23:46:33 INFO - 'tpaint', 23:46:33 INFO - 'sessionrestore', 23:46:33 INFO - 'sessionrestore_no_auto_restore', 23:46:33 INFO - 'tabpaint']}, 23:46:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:46:33 INFO - 'ts_paint', 23:46:33 INFO - 'tpaint', 23:46:33 INFO - 'sessionrestore', 23:46:33 INFO - 'sessionrestore_no_auto_restore', 23:46:33 INFO - 'tabpaint']}, 23:46:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:46:33 INFO - 'ts_paint', 23:46:33 INFO - 'tpaint', 23:46:33 INFO - 'sessionrestore', 23:46:33 INFO - 'sessionrestore_no_auto_restore', 23:46:33 INFO - 'tabpaint']}, 23:46:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['a11yr', 23:46:33 INFO - 'ts_paint', 23:46:33 INFO - 'tpaint', 23:46:33 INFO - 'sessionrestore', 23:46:33 INFO - 'sessionrestore_no_auto_restore', 23:46:33 INFO - 'tabpaint']}, 23:46:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['a11yr', 23:46:33 INFO - 'ts_paint', 23:46:33 INFO - 'tpaint', 23:46:33 INFO - 'sessionrestore', 23:46:33 INFO - 'sessionrestore_no_auto_restore', 23:46:33 INFO - 'tabpaint']}, 23:46:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['tsvgx', 23:46:33 INFO - 'tsvgr_opacity', 23:46:33 INFO - 'tart', 23:46:33 INFO - 'tscrollx', 23:46:33 INFO - 'cart']}, 23:46:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:46:33 INFO - 'tsvgr_opacity', 23:46:33 INFO - 'tart', 23:46:33 INFO - 'tscrollx', 23:46:33 INFO - 'cart']}, 23:46:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'talos_options': ['--disable-e10s'], 23:46:33 INFO - 'tests': ['tp5o']}, 23:46:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'tests': ['tp5o']}, 23:46:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'talos_options': ['--disable-e10s', 23:46:33 INFO - '--xperf_path', 23:46:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:46:33 INFO - 'tests': ['tp5n']}, 23:46:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:46:33 INFO - 'talos_options': ['--xperf_path', 23:46:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:46:33 INFO - 'tests': ['tp5n']}}, 23:46:33 INFO - 'talos.zip': {'path': '', 23:46:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:46:33 INFO - Running post-action listener: _resource_record_post_action 23:46:33 INFO - [mozharness: 2016-09-07 06:46:33.839839Z] Finished populate-webroot step (success) 23:46:33 INFO - [mozharness: 2016-09-07 06:46:33.839972Z] Running create-virtualenv step. 23:46:33 INFO - Running pre-action listener: _resource_record_pre_action 23:46:33 INFO - Running main action method: create_virtualenv 23:46:33 INFO - Creating virtualenv /builds/slave/test/build/venv 23:46:33 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 23:46:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:46:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:46:33 INFO - Using real prefix '/tools/python27' 23:46:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:46:34 INFO - Installing distribute.............................................................................................................................................................................................done. 23:46:37 INFO - Installing pip.................done. 23:46:37 INFO - Return code: 0 23:46:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:46:37 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')]} 23:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:37 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')]} 23:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:37 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:37 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 23:46:37 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 23:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:37 INFO - 'HOME': '/Users/cltbld', 23:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:37 INFO - 'LOGNAME': 'cltbld', 23:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:37 INFO - 'MOZ_NO_REMOTE': '1', 23:46:37 INFO - 'NO_EM_RESTART': '1', 23:46:37 INFO - 'PAGER': '/bin/cat', 23:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:37 INFO - 'PWD': '/builds/slave/test', 23:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:37 INFO - 'SHELL': '/bin/bash', 23:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:37 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:37 INFO - 'USER': 'cltbld', 23:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:37 INFO - 'XPC_FLAGS': '0x0', 23:46:37 INFO - 'XPC_SERVICE_NAME': '0', 23:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:37 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 23:46:37 INFO - Cleaning up... 23:46:37 INFO - Return code: 0 23:46:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:46:37 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')]} 23:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:37 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')]} 23:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:37 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:37 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 23:46:37 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 23:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:37 INFO - 'HOME': '/Users/cltbld', 23:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:37 INFO - 'LOGNAME': 'cltbld', 23:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:37 INFO - 'MOZ_NO_REMOTE': '1', 23:46:37 INFO - 'NO_EM_RESTART': '1', 23:46:37 INFO - 'PAGER': '/bin/cat', 23:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:37 INFO - 'PWD': '/builds/slave/test', 23:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:37 INFO - 'SHELL': '/bin/bash', 23:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:37 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:37 INFO - 'USER': 'cltbld', 23:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:37 INFO - 'XPC_FLAGS': '0x0', 23:46:37 INFO - 'XPC_SERVICE_NAME': '0', 23:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:38 INFO - Downloading/unpacking psutil>=3.1.1 23:46: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 23:46: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 23:46: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 23:46: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 23:46: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 23:46: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 23:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:46:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:46:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:46:41 INFO - Installing collected packages: psutil 23:46:41 INFO - Running setup.py install for psutil 23:46:42 INFO - building 'psutil._psutil_osx' extension 23:46:42 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 23:46:42 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 23:46:42 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 23:46:42 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 23:46:42 INFO - building 'psutil._psutil_posix' extension 23:46:42 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 23:46:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:46:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:46:42 INFO - ^ 23:46:42 INFO - 1 warning generated. 23:46:42 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 23:46:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:46:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:46:43 INFO - Successfully installed psutil 23:46:43 INFO - Cleaning up... 23:46:43 INFO - Return code: 0 23:46:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:46:43 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')]} 23:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:43 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')]} 23:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:43 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:43 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 23:46:43 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 23:46:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:43 INFO - 'HOME': '/Users/cltbld', 23:46:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:43 INFO - 'LOGNAME': 'cltbld', 23:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:43 INFO - 'MOZ_NO_REMOTE': '1', 23:46:43 INFO - 'NO_EM_RESTART': '1', 23:46:43 INFO - 'PAGER': '/bin/cat', 23:46:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:43 INFO - 'PWD': '/builds/slave/test', 23:46:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:43 INFO - 'SHELL': '/bin/bash', 23:46:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:43 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:43 INFO - 'USER': 'cltbld', 23:46:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:43 INFO - 'XPC_FLAGS': '0x0', 23:46:43 INFO - 'XPC_SERVICE_NAME': '0', 23:46:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:46:43 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 23:46:43 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 23:46:43 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 23:46:43 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 23:46:43 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 23:46:43 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 23:46:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:46:45 INFO - Installing collected packages: mozsystemmonitor 23:46:45 INFO - Running setup.py install for mozsystemmonitor 23:46:46 INFO - Successfully installed mozsystemmonitor 23:46:46 INFO - Cleaning up... 23:46:46 INFO - Return code: 0 23:46:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:46:46 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')]} 23:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:46 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')]} 23:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:46 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:46 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 23:46:46 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 23:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:46 INFO - 'HOME': '/Users/cltbld', 23:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:46 INFO - 'LOGNAME': 'cltbld', 23:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:46 INFO - 'MOZ_NO_REMOTE': '1', 23:46:46 INFO - 'NO_EM_RESTART': '1', 23:46:46 INFO - 'PAGER': '/bin/cat', 23:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:46 INFO - 'PWD': '/builds/slave/test', 23:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:46 INFO - 'SHELL': '/bin/bash', 23:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:46 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:46 INFO - 'USER': 'cltbld', 23:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:46 INFO - 'XPC_FLAGS': '0x0', 23:46:46 INFO - 'XPC_SERVICE_NAME': '0', 23:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:46 INFO - Downloading/unpacking jsonschema==2.5.1 23:46:46 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 23:46:46 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 23:46:46 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 23:46:46 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 23:46:46 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 23:46:46 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 23:46:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:46:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:46:48 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 23:46:48 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 23:46:48 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 23:46:48 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 23:46:48 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 23:46:48 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 23:46:49 INFO - Downloading functools32-3.2.3-2.tar.gz 23:46:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:46:49 INFO - warning: no files found matching '*.txt' 23:46:49 INFO - no previously-included directories found matching 'build' 23:46:49 INFO - no previously-included directories found matching 'dist' 23:46:49 INFO - no previously-included directories found matching '.git*' 23:46:49 INFO - Installing collected packages: jsonschema, functools32 23:46:49 INFO - Running setup.py install for functools32 23:46:49 INFO - warning: no files found matching '*.txt' 23:46:49 INFO - no previously-included directories found matching 'build' 23:46:49 INFO - no previously-included directories found matching 'dist' 23:46:49 INFO - no previously-included directories found matching '.git*' 23:46:49 INFO - Successfully installed jsonschema functools32 23:46:49 INFO - Cleaning up... 23:46:49 INFO - Return code: 0 23:46:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:46:49 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')]} 23:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:49 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')]} 23:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:49 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:49 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 23:46:49 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 23:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:49 INFO - 'HOME': '/Users/cltbld', 23:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:49 INFO - 'LOGNAME': 'cltbld', 23:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:49 INFO - 'MOZ_NO_REMOTE': '1', 23:46:49 INFO - 'NO_EM_RESTART': '1', 23:46:49 INFO - 'PAGER': '/bin/cat', 23:46:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:49 INFO - 'PWD': '/builds/slave/test', 23:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:49 INFO - 'SHELL': '/bin/bash', 23:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:49 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:49 INFO - 'USER': 'cltbld', 23:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:49 INFO - 'XPC_FLAGS': '0x0', 23:46:49 INFO - 'XPC_SERVICE_NAME': '0', 23:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:46:49 INFO - Cleaning up... 23:46:49 INFO - Return code: 0 23:46:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:46:49 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')]} 23:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:49 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')]} 23:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:49 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:49 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 23:46:49 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 23:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:49 INFO - 'HOME': '/Users/cltbld', 23:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:49 INFO - 'LOGNAME': 'cltbld', 23:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:49 INFO - 'MOZ_NO_REMOTE': '1', 23:46:49 INFO - 'NO_EM_RESTART': '1', 23:46:49 INFO - 'PAGER': '/bin/cat', 23:46:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:49 INFO - 'PWD': '/builds/slave/test', 23:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:49 INFO - 'SHELL': '/bin/bash', 23:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:49 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:49 INFO - 'USER': 'cltbld', 23:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:49 INFO - 'XPC_FLAGS': '0x0', 23:46:49 INFO - 'XPC_SERVICE_NAME': '0', 23:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:50 INFO - Downloading/unpacking blobuploader==1.2.4 23:46:50 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 23:46:50 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 23:46:50 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 23:46:50 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 23:46:50 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 23:46:50 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 23:46:52 INFO - Downloading blobuploader-1.2.4.tar.gz 23:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:46:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:46:52 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 23:46:52 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 23:46:52 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 23:46:52 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 23:46:52 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 23:46:52 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 23:46:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:46:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:46:53 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 23:46:53 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 23:46:53 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 23:46:53 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 23:46:53 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 23:46:53 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 23:46:53 INFO - Downloading docopt-0.6.1.tar.gz 23:46:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:46:53 INFO - Installing collected packages: blobuploader, requests, docopt 23:46:53 INFO - Running setup.py install for blobuploader 23:46:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:46:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:46:54 INFO - Running setup.py install for requests 23:46:54 INFO - Running setup.py install for docopt 23:46:54 INFO - Successfully installed blobuploader requests docopt 23:46:54 INFO - Cleaning up... 23:46:54 INFO - Return code: 0 23:46:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:46:54 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')]} 23:46:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:54 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')]} 23:46:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:54 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:54 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 23:46:54 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 23:46:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:54 INFO - 'HOME': '/Users/cltbld', 23:46:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:54 INFO - 'LOGNAME': 'cltbld', 23:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:54 INFO - 'MOZ_NO_REMOTE': '1', 23:46:54 INFO - 'NO_EM_RESTART': '1', 23:46:54 INFO - 'PAGER': '/bin/cat', 23:46:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:54 INFO - 'PWD': '/builds/slave/test', 23:46:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:54 INFO - 'SHELL': '/bin/bash', 23:46:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:54 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:54 INFO - 'USER': 'cltbld', 23:46:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:54 INFO - 'XPC_FLAGS': '0x0', 23:46:54 INFO - 'XPC_SERVICE_NAME': '0', 23:46:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-pksp7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ua5vOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-RXihEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-LL8qSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-LSmrBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-7rUyyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-9_e4NN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Z8v6pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:46:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:46:55 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Xq9RPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-MY1SCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-5JctOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-OqTJ0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-3fPXaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-0PtRSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-ZrOjnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-j4Ket3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:46:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:46:56 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-NmAHK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:46:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:46:56 INFO - Running setup.py install for manifestparser 23:46:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:46:57 INFO - Running setup.py install for mozcrash 23:46:57 INFO - Running setup.py install for mozdebug 23:46:57 INFO - Running setup.py install for mozdevice 23:46:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:46:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:46:57 INFO - Running setup.py install for mozfile 23:46:57 INFO - Running setup.py install for mozhttpd 23:46:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:46:57 INFO - Running setup.py install for mozinfo 23:46:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:46:57 INFO - Running setup.py install for mozInstall 23:46:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Running setup.py install for mozleak 23:46:58 INFO - Running setup.py install for mozlog 23:46:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Running setup.py install for moznetwork 23:46:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Running setup.py install for mozprocess 23:46:58 INFO - Running setup.py install for mozprofile 23:46:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:46:58 INFO - Running setup.py install for mozrunner 23:46:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:46:59 INFO - Running setup.py install for mozscreenshot 23:46:59 INFO - Running setup.py install for moztest 23:46:59 INFO - Running setup.py install for mozversion 23:46:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:46:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:46:59 INFO - Cleaning up... 23:46:59 INFO - Return code: 0 23:46:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:46: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')]} 23:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46: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')]} 23:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:46:59 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:46:59 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 23:46:59 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 23:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:46:59 INFO - 'HOME': '/Users/cltbld', 23:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:46:59 INFO - 'LOGNAME': 'cltbld', 23:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:46:59 INFO - 'MOZ_NO_REMOTE': '1', 23:46:59 INFO - 'NO_EM_RESTART': '1', 23:46:59 INFO - 'PAGER': '/bin/cat', 23:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:46:59 INFO - 'PWD': '/builds/slave/test', 23:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:46:59 INFO - 'SHELL': '/bin/bash', 23:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:46:59 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:46:59 INFO - 'USER': 'cltbld', 23:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:46:59 INFO - 'XPC_FLAGS': '0x0', 23:46:59 INFO - 'XPC_SERVICE_NAME': '0', 23:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:46:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-N7wjIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:46:59 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)) 23:46:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:46:59 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-P7lLT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-Pz6RS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-GYrsUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-kMdAZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-2H7V2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-XuNUYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-PjJb2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-hW9NlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-QUVwYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-vvws6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:47:00 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)) 23:47:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:47:00 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-zUxbx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:47:01 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)) 23:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:47:01 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-6QHJbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:47:01 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)) 23:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:47:01 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-S3m8Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:47:01 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)) 23:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:01 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-f8Wthw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:47:01 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)) 23:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:47:01 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-FShV8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:47:01 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)) 23:47:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:47:01 INFO - Running setup.py (path:/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/pip-CWvJRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:47:01 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)) 23:47:01 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)) 23:47:01 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)) 23:47:01 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)) 23:47:01 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)) 23:47:01 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)) 23:47:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:47:01 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 23:47:01 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 23:47:01 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 23:47:01 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 23:47:01 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 23:47:01 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 23:47:04 INFO - Downloading blessings-1.6.tar.gz 23:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:47:04 INFO - Installing collected packages: blessings 23:47:04 INFO - Running setup.py install for blessings 23:47:04 INFO - Successfully installed blessings 23:47:04 INFO - Cleaning up... 23:47:04 INFO - Return code: 0 23:47:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:47:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:47:04 INFO - Reading from file tmpfile_stdout 23:47:04 INFO - Current package versions: 23:47:04 INFO - blessings == 1.6 23:47:04 INFO - blobuploader == 1.2.4 23:47:04 INFO - docopt == 0.6.1 23:47:04 INFO - functools32 == 3.2.3-2 23:47:04 INFO - jsonschema == 2.5.1 23:47:04 INFO - manifestparser == 1.1 23:47:04 INFO - mozInstall == 1.12 23:47:04 INFO - mozcrash == 0.17 23:47:04 INFO - mozdebug == 0.1 23:47:04 INFO - mozdevice == 0.48 23:47:04 INFO - mozfile == 1.2 23:47:04 INFO - mozhttpd == 0.7 23:47:04 INFO - mozinfo == 0.9 23:47:04 INFO - mozleak == 0.1 23:47:04 INFO - mozlog == 3.3 23:47:04 INFO - moznetwork == 0.27 23:47:04 INFO - mozprocess == 0.23 23:47:04 INFO - mozprofile == 0.28 23:47:04 INFO - mozrunner == 6.12 23:47:04 INFO - mozscreenshot == 0.1 23:47:04 INFO - mozsystemmonitor == 0.3 23:47:04 INFO - moztest == 0.7 23:47:04 INFO - mozversion == 1.4 23:47:04 INFO - psutil == 3.1.1 23:47:04 INFO - requests == 1.2.3 23:47:04 INFO - wsgiref == 0.1.2 23:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:47:04 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')]} 23:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:04 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')]} 23:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:04 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:47:04 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 23:47:04 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 23:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:04 INFO - 'HOME': '/Users/cltbld', 23:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:04 INFO - 'LOGNAME': 'cltbld', 23:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:04 INFO - 'MOZ_NO_REMOTE': '1', 23:47:04 INFO - 'NO_EM_RESTART': '1', 23:47:04 INFO - 'PAGER': '/bin/cat', 23:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:04 INFO - 'PWD': '/builds/slave/test', 23:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:04 INFO - 'SHELL': '/bin/bash', 23:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:47:04 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:04 INFO - 'USER': 'cltbld', 23:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:04 INFO - 'XPC_FLAGS': '0x0', 23:47:04 INFO - 'XPC_SERVICE_NAME': '0', 23:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 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)) 23:47:04 INFO - Cleaning up... 23:47:04 INFO - Return code: 0 23:47:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:47:04 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')]} 23:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:04 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')]} 23:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:47:04 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 0x1105aea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110643618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9c3c8ec20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, '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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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 23:47:04 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 23:47:04 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 23:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:04 INFO - 'HOME': '/Users/cltbld', 23:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:04 INFO - 'LOGNAME': 'cltbld', 23:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:04 INFO - 'MOZ_NO_REMOTE': '1', 23:47:04 INFO - 'NO_EM_RESTART': '1', 23:47:04 INFO - 'PAGER': '/bin/cat', 23:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:04 INFO - 'PWD': '/builds/slave/test', 23:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:04 INFO - 'SHELL': '/bin/bash', 23:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:47:04 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:04 INFO - 'USER': 'cltbld', 23:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:04 INFO - 'XPC_FLAGS': '0x0', 23:47:04 INFO - 'XPC_SERVICE_NAME': '0', 23:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:47:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:47:05 INFO - Cleaning up... 23:47:05 INFO - Return code: 0 23:47:05 INFO - Running post-action listener: _resource_record_post_action 23:47:05 INFO - Running post-action listener: _start_resource_monitoring 23:47:05 INFO - Starting resource monitoring. 23:47:05 INFO - [mozharness: 2016-09-07 06:47:05.251843Z] Finished create-virtualenv step (success) 23:47:05 INFO - [mozharness: 2016-09-07 06:47:05.252829Z] Running install step. 23:47:05 INFO - Running pre-action listener: _resource_record_pre_action 23:47:05 INFO - Running main action method: install 23:47:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:47:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:47:05 INFO - Reading from file tmpfile_stdout 23:47:05 INFO - Detecting whether we're running mozinstall >=1.0... 23:47:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:47:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:47:05 INFO - Reading from file tmpfile_stdout 23:47:05 INFO - Output received: 23:47:05 INFO - Usage: mozinstall [options] installer 23:47:05 INFO - Options: 23:47:05 INFO - -h, --help show this help message and exit 23:47:05 INFO - -d DEST, --destination=DEST 23:47:05 INFO - Directory to install application into. [default: 23:47:05 INFO - "/builds/slave/test"] 23:47:05 INFO - --app=APP Application being installed. [default: firefox] 23:47:05 INFO - mkdir: /builds/slave/test/build/application 23:47:05 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'] 23:47:05 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 23:47:39 INFO - Reading from file tmpfile_stdout 23:47:39 INFO - Output received: 23:47:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:47:39 INFO - Running post-action listener: _resource_record_post_action 23:47:39 INFO - [mozharness: 2016-09-07 06:47:39.514967Z] Finished install step (success) 23:47:39 INFO - [mozharness: 2016-09-07 06:47:39.515117Z] Running run-tests step. 23:47:39 INFO - Running pre-action listener: _resource_record_pre_action 23:47:39 INFO - Running main action method: run_tests 23:47:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:47:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:47:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:47:39 INFO - 2016-09-06 23:47:39.528 screenresolution[1647:10040] starting screenresolution argv=screenresolution get 23:47:39 INFO - 2016-09-06 23:47:39.530 screenresolution[1647:10040] Display 0: 1600x1200x32@60 23:47:39 INFO - 2016-09-06 23:47:39.535 screenresolution[1648:10042] starting screenresolution argv=screenresolution list 23:47:39 INFO - Available Modes on Display 0 23:47:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:47:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:47:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:47:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:47:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:47:39 INFO - Intel Iris: 23:47:39 INFO - Chipset Model: Intel Iris 23:47:39 INFO - Type: GPU 23:47:39 INFO - Bus: Built-In 23:47:39 INFO - VRAM (Dynamic, Max): 1536 MB 23:47:39 INFO - Vendor: Intel (0x8086) 23:47:39 INFO - Device ID: 0x0a2e 23:47:39 INFO - Revision ID: 0x0009 23:47:39 INFO - Displays: 23:47:39 INFO - Display: 23:47:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:47:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:47:39 INFO - Display Serial Number: 1600x1200 60 23:47:39 INFO - Main Display: Yes 23:47:39 INFO - Mirror: Off 23:47:39 INFO - Online: Yes 23:47:39 INFO - Rotation: Supported 23:47:39 INFO - Return code: 0 23:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:47:39 INFO - Python 2.7.3 23:47:39 INFO - Return code: 0 23:47:39 INFO - grabbing minidump binary from tooltool 23:47:39 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')]} 23:47:39 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 0x110512d30>, '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 0x110277db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11050ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:47:39 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 23:47:39 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 23:47:39 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 23:47:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:47:39 INFO - Return code: 0 23:47:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:47:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:47:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:47:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:47:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SCeDlpK3Z0/Render', 23:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:47:39 INFO - 'HOME': '/Users/cltbld', 23:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:47:39 INFO - 'LOGNAME': 'cltbld', 23:47:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:47:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:47:39 INFO - 'MOZ_NO_REMOTE': '1', 23:47:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:47:39 INFO - 'NO_EM_RESTART': '1', 23:47:39 INFO - 'PAGER': '/bin/cat', 23:47:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:47:39 INFO - 'PWD': '/builds/slave/test', 23:47:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:47:39 INFO - 'SHELL': '/bin/bash', 23:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hug78Uxt7s/Listeners', 23:47:39 INFO - 'TMPDIR': '/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/', 23:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:47:39 INFO - 'USER': 'cltbld', 23:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:47:39 INFO - 'XPC_FLAGS': '0x0', 23:47:39 INFO - 'XPC_SERVICE_NAME': '0', 23:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:47:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:47:40 INFO - mozversion application_buildid: 20160906185516 23:47:40 INFO - mozversion application_changeset: a6b6a93eb41a05e310a11f0172f01ba9b21d3eac 23:47:40 INFO - mozversion application_display_name: Nightly 23:47:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:47:40 INFO - mozversion application_name: Firefox 23:47:40 INFO - mozversion application_remotingname: firefox 23:47:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:47:40 INFO - mozversion application_vendor: Mozilla 23:47:40 INFO - mozversion application_version: 51.0a1 23:47:40 INFO - mozversion platform_buildid: 20160906185516 23:47:40 INFO - mozversion platform_changeset: a6b6a93eb41a05e310a11f0172f01ba9b21d3eac 23:47:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:47:40 INFO - mozversion platform_version: 51.0a1 23:47:40 INFO - using testdate: 1473230860 23:47:40 INFO - actual date: 1473230860 23:47:40 INFO - starting webserver on 'localhost:49271' 23:47:40 INFO - SUITE-START | Running 2 tests 23:47:40 INFO - TEST-START | dromaeo_css 23:47:40 INFO - operating with platform_type : mac_ 23:47:40 INFO - Initialising browser for dromaeo_css test... 23:47:40 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpBQYNgv/profile http://localhost:49271/getInfo.html) 23:47:42 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 23:47:42 INFO - PROCESS | 1658 | colorDepth:24 23:47:42 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 23:47:42 INFO - PROCESS | 1658 | __metrics 23:47:43 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 23:47:43 INFO - TEST-INFO | 1658: exit 0 23:47:43 INFO - Browser initialized. 23:47:43 INFO - Running cycle 1/1 for dromaeo_css test... 23:47:43 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpBQYNgv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:47:55 INFO - PROCESS | 1662 | RSS: Main: 243748864 23:47:55 INFO - PROCESS | 1662 | 23:48:45 INFO - PROCESS | 1662 | 1473230925265 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] 23:50:57 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 23:50:58 INFO - PROCESS | 1662 | RSS: Main: 266559488 23:50:58 INFO - PROCESS | 1662 | 23:54:00 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 23:54:01 INFO - PROCESS | 1662 | RSS: Main: 273383424 23:54:01 INFO - PROCESS | 1662 | 23:57:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 23:57:03 INFO - PROCESS | 1662 | RSS: Main: 273969152 23:57:03 INFO - PROCESS | 1662 | 00:00:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 00:00:06 INFO - PROCESS | 1662 | RSS: Main: 277168128 00:00:06 INFO - PROCESS | 1662 | 00:03:09 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 00:03:09 INFO - PROCESS | 1662 | RSS: Main: 280543232 00:03:09 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 00:06:12 INFO - PROCESS | 1662 | RSS: Main: 280629248 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | __start_tp_report 00:06:12 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 00:06:12 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 00:06:12 INFO - PROCESS | 1662 | |i|pagename|runs| 00:06:12 INFO - PROCESS | 1662 | |0;dojo.html;3207.7433805295577;3290.161008942721;3312.9503057454126;3338.7996720196775;3365.8148801815905;5539.473749993749;5569.053260945639;5580.553555715544;5651.350094739096;5670.659760414378;5457.2359869618185;5523.309586301712;5582.190582365554;5620.831375058745;5663.66018038918;3687.483752274684;4978.153713868651;5092.694438333693;5188.558972487343;5207.86980325493;4169.583041695836;4522.29904364824;4652.767361631905;4831.154547954112;5348.812791552296;5114.84655460333;5187.48125187482;5205.349331333583;5207.0887594670785;5227.424983251839;5159.974200128975;5171.689698618095;5180.6373553851245;5221.71280579568;5280.894382112337;4179.770112643803;5127.564157046618;5177.275181474596;5208;5266.499682530153;4394.318880573516;5502.58730595203;5569.248151499536;5634.352049514277;5659.122835960433;5031.52200540948;6165.907511387333;6170.84572885677;6180.443760061616;6206.6586337751405;6242.09489624002;6242.406971337716;6250.4374606285655;6281.780137695158;6283.7172327244925;4671.346011558385;5593.098346562188;5665.433456654301;5719.25649665545;5723.141528770676;6281.057841323838;6295.653739044396;6296.401841825019;6296.464800492009;6331.778387756406;6078.6048906820915;6085.604435711664;6088.543359248074;6093.847653808691;6100.633961962297;6055.212822333068;6081.391860813883;6111.804454079419;6171.043488259327;6211.223597050369;5959.642421454727;6048.395160484004;6080.0575910733905;6121.056693971993;6128.080787881764;5123.820666276662;6173.41352571505;6183.938160618336;6197.845053873599;6198.907016394667;4895.7062576245535;4898.21628539432;4901.166801643729;4903.141950158708;4934.383202099738;4198.202341555095;4254.234237837157;4273.465816772903;4299.806508707115;4429.402030725875;5196.714180720097;5629.408912064257;5705.2012718219485;5711.771529138871;5726.627769194989;6203.937960620427;6242.937570624326;6243.562950593415;6250.062490626351;6275.874482510324;5539.8892022160135;5570.972145139248;5622.606417550814;5651.039323315046;5692.601517893708;5268.288781014514;5311.628186027018;5321.600879934021;5328.387235467893;5329.813456529003;6283.3088360281245;6313.368663133833;6319.747210111636;6322.430981211713;6323.557350985571;6060.606060606046;6147;6158.107074474266;6177.567570270038;6244.094606281973;6220.657863817533;6270.592411493239;6285.937140628535;6290.716917738804;6299.023651334051;6142.692865356625;6158.876822463436;6186.81439556814;6187.16473276102;6201.844953876189;6095.177151084724;6140.478059365004;6201.10084037821;6211.596246243979;6271.529635277281;5941.643501389931;6050.30421501533;6072.787452439144;6091.695415229309;6106.572539922163;4879.146149423766;5756.30924289088;6008.519318454601;6016.518678505797;6056.545759068175;4415.006623509685;5718.857028574319;5795.724940513081;5805.042168042226;5957.255343082114;6016.42843929826;6039.849003774941;6048.758049678139;6059.0608455689435;6095.939040609537;4864.197407427876;5935.910961335497;5943.52451803846;5947.3457919627945;5960.1357803117435 00:06:12 INFO - PROCESS | 1662 | |1;ext.html;503.39341093981733;507.8273387048404;522.7203446156307;522.8966879883446;564.7486868343584;37597.62402375979;39971.400428993555;39983;40053.399199012005;40108.19783604326;40238.396424053666;41034.179316413654;41566.96082597937;41760;41779.79110104445;9444.433333999965;9592.760180995496;9674.854877176847;9677.661281855137;9745.951270243639;9512.857307140399;9586.376885502421;9588.808223835518;9613.038696130401;9624.80750384992;9616.230701543895;9624;9639.469829159394;9671.162479128943;9684;9579.994100619439;9636.325457217998;9650;9653.372530785477;9661.46861922594;9456;9456.196223321025;9469.195118414944;9536.570854311503;9571.37786043905;39065.60934390648;39154.412683809765;39191.804040979616;39296.607033929584;39557.80221098905;3081.5531747896653;3146;3244.545763593075;3469.4991810195115;3859.0931131184147;30263.243418914484;30580;31034.689653103407;31624.525632115536;31707;3025.6369235691636;3150.82670453125;3191.789430970753;3232.7253726144017;3300.389427955836;496739;498784;499308;502026;503437;61851;62404.375956240765;62559;62568.68715656484;62659;33262.66737332644;34120.48819267713;64274.35725642777;64856.027159592646;65083.02375464372;15789.605259868595;16386.75419868703;16423.507294781175;16428.589285267964;16481.17594120289;12049.879501204876;12490.937545312216;12521.123521353418;12548.372581370895;12602.306873121883;18924.71612925807;18953.147108380155;18958.71561926572;18974.620507590047;19204.615907681768;6345.555811093272;7179.317964793337;7243.855122897513;7275.636218189069;8107.4324797263625;803.2449497472358;803.8593246181895;906.5875026862761;958.6500927161675;993.5578667492896;25818.87090564535;29619.111426657233;29671.109866704035;29896.850515747283;30324.69675303263;1108.634150730257;1136.4431428600092;1147.5868687272575;1167.4688016952266;1169.9645813454983;14992.175430351435;15330.386784528493;15426.074435533905;15565.610859728371;15703.580432187742;542226;654222;662364;664135;665496;56216;56793;56875;56888;57176.71411642915;1323.1730168644597;1330.946762129504;1368.2406264523086;1370.4381203706432;1371.376023909139;28973.275668108465;28981.27546811262;29100.563491547222;29557.704422956354;29675;48759;49039.50960490349;49190.508094918594;49229.184995601514;49246;577068;578425;578691;579278;580413;25575.872120639277;25654.486910262065;26177.083802066838;26269.34326641849;26285.34286642849;44783.32825007693;45894.541054589026;46437.76781116073;46455.53544464647;46893.53106468892;29348.853255733586;32902.177445564055;37303;37577.81211093927;38902;35826.74606388764;37463.81268093642;37789.81105094457;38027.42958855675;38848.805755971036;37176.62823371732;37550.81224593859;38120.04699882525;38155.618443815205;38230;30780.53829192519;30943.0717078488;32842.50736238911;33073.83463082669;34347.4847877287;23800.761992379856;24153.637695434933;24998.75001249964;25427.11005114812;25857.353566160997 00:06:12 INFO - PROCESS | 1662 | |2;jquery.html;304.4838997898563;367.590136997248;372.79496277047616;375.8477816484325;392.8919547124542;19495.220191192355;20358.69461958068;20943.05756240969;21301.14795408186;21490.247841325556;20088.49778755532;20096;20437.48906277344;20568.794312056874;20785.896070519662;5726.026575482178;5812.3025236971625;6035.155078289021;6104.389561043905;6120.1125836753645;5932.644041357532;6050.001749711314;6053.365591290371;6059.878802423949;6069.848253793646;5702.971485142569;5912.733926973275;5949.8215053548465;5954.285485741717;6015.278166620012;5286.94261147772;5837.941620583782;5901.704914754245;5948.107783832416;5972.163897054415;4888.217885138396;5141.2288156776785;5141.280220769096;5147.356580427447;5160.174372100483;24642.630360544605;24855.378615534577;24921.87539062311;25132.230165475143;25595.232143035737;2158;2218.378853920905;2834.3419099826624;3089.7991630544166;3135.8745650173964;15101.244937753178;15242.39030438781;15356.539303820904;15731.921340393339;15908.965917215344;1857.9907100464545;1858.2009735813594;1862.8416584590327;1880.2666959885655;1910.8757930734457;109311.45344273387;110361.34457983136;116873;119875.80124198821;122507;8453.915460845436;8998.325125615605;9108.59011344491;10226.948865255627;10498.895011049792;13135.737285254241;13235.213246211832;13296.202227866359;13317.33413329349;13324.067315287837;7234.276572342724;7892.368610511146;8287.254147126787;8349.457285276438;8431.915680843113;8247.175282471824;8282.875756863652;8358.749237522885;8511.702090426805;8525.360597955178;5550.555955523549;6062.484378905274;6410.1154480483965;6542.182227221598;6563.540552161303;1678.4628918746128;1830.8352248297717;1838.9172487238106;1839.8804077735172;1840.2087102546034;754.5171090502083;876.2683159561838;939.572494514994;980.9509524523745;1017.9083882450615;20416.285430009877;20438.182472701224;22036.449088772588;22045.228417005328;22083.66874496884;929.6327950459665;942.7690215897012;947.5309721687809;995.8904520502739;1019.954102065394;8367.748967530984;10782.892171078345;11398.58079529396;11398.715032124263;11593.826092608617;583659;589120;596835;598215;600048;67552;104158.47920760348;111188.88811111785;118238.8176118245;118543;949.5062567464879;978.6770365779419;985.9457729824785;1001.4992503748126;1005.6480231918916;18527.35154269648;19179.424617261506;19218.846869187895;19715;19734.30929917446;33396;49581.50418495769;51120.74439627778;51151;51902.480975191276;53783;61975.07037394533;69385.65307173633;70726.64636676783;75069.6246518764;7643.464957452925;7860.724874629361;8053.436259461781;8064.717734879251;8254.917450825415;13071.647103521458;13166.144200626928;13358;13417.463301467831;13639.454421823017;11247.7750444989;11252.381119038204;11324.716882077684;11378.54485820558;11589.420528973349;9099;9421.304165250322;9499.810003800023;11223.60717374888;11591.884081159418;11278.718032049264;11483.655490335303;11518.424078796193;11620.767584648429;11885;6900.3444672756;7353.301436363537;7384.446166537424;8008.719694810887;8238.299744521782;10697.304675196086;10952.671419857417;11249.831252531056;11351.772964540498;11436.885631143583 00:06:12 INFO - PROCESS | 1662 | |3;mootools.html;1342.6911810283634;1499.0555949751658;1561.3988614383459;1716.5622766194626;1846.169223849268;7985.9600701996405;8422.241998220159;8451.239388455038;8988.68539601115;8998.23515001225;7360.815979600499;8492.575371231445;8648.13518648135;8717.259032982189;8765.211130998208;1978.307592342683;2349.001674288429;2363.9409014774637;2389.498205376873;2395.2215529952746;2326.139328448468;2392.982982232544;2393.545226406986;2395.269442819948;2396.0176327705644;1828.5611453251256;2355.5171189906027;2375.9643605345846;2380.392999785061;2392.1866565367773;2354.3878591566145;2361.244401791427;2370.798482129004;2371.312319427364;2374.536965291769;1948.824605785486;2337.415646088478;2339.836211465198;2342.7657234276435;2356.599378105726;8744.650213991432;8834.69078582253;8846.646134154626;8852.51311177885;8860.601272942733;5256.7371631418655;5427.294451721291;5438.238646589481;5450.373207081197;5451.809186678487;3492.6029588164683;3558.87543935961;3629.891103266906;3692.3538380783257;3702.2965636528975;1055.3404122423485;1081.3728037738092;1081.556561809662;1108.7782443511248;1109.1625822504038;11622.418879056013;11788.528458861636;11817.704557386049;11917.582884599084;11956.940215298955;4614.838480653161;4668.486466488684;4815.2054910939305;4862.367892173995;4869.9513004870205;8391.748247552583;9850.80298394028;9887.209023278121;9913.256505762096;9941.900580994243;5309.8938021239355;5321.893562128813;5330.2804121443105;5336.226247194135;5337.599680024014;4342.978285108554;4742.976285118552;4877.6829506081995;4914.483979182134;4915.778789954461;3886.3393223152134;3929.0373858404873;3970.4242884781543;4015.9799201003807;4049.919001619951;1289.8323217981601;1315.1845855569609;1317.0912070671206;1319.182107093608;1323.483841301893;781.5427974634789;891.5542228885557;918.3846822628855;930.3627015494408;932.2681694869631;4695.436547614308;5651.237082993825;5777.768889244468;5963.135345374896;6037.245344331958;945.7304668169538;965.5896244096231;970.6505657963128;979.0747743382577;990.4305024610877;3954.1103251768122;3956.208758248362;3970.4838371011583;4094.529129150126;4113.650339721157;10407.479626018669;10623.256372054037;10721.624743133953;10780.083692886192;10787.190960677824;10573.894261057445;10701.571937122584;10751.139908807276;10773.569057237622;10798.028177464066;8016.71941482057;8038.437309388403;8086.312663423557;8261.17388261181;8279.840822284885;5445.264889239902;5647.20939068539;5716.685582292847;5735.512481439125;5768.423157684198;9943.154831839085;9970.950145249517;10046.949765251127;10143.492825358555;10150.13723833453;7583;9569.569369378256;9644.662436814679;10226.28416010872;10320.019598138166;5388.865278367915;6480.967595161994;6608.1079054328975;6832.248452670302;6949.895751563832;8410.453320534145;8498.447600905922;8544.786380340542;8549.358798090292;8557.786055348666;7093.716251349888;7204.4956853019785;7223.169335526271;7289.088863892013;7299.087614048244;7096.53872498286;7107.893381599383;7123.287671233043;7140.178879428932;7207.387372073433;6566.704498297684;6619.238787539494;6629;6671.46628269747;6671.966640166962;4078.4494093297026;4639.976800115978;4642.86071417858;4665.953340466552;4774.785134669018;4287.614114729689;4683.555062269079;4790.976045119752;4847;4962.33008543842 00:06:12 INFO - PROCESS | 1662 | |4;prototype.html;285.8613572417377;287.0369908955455;294.61405558718076;301.4829567292095;302.14794280130013;5044.646874718771;5542.916856247154;5545.306836645419;5644.435556444359;5659.490645841878;5091.541761241488;5200.361886005906;5392.622516423852;5505.807296744605;5616.9157462638095;3864.087276797989;4221.746695198282;4268.018355778173;4275.700700950934;4318.2227199104145;4099.918001639965;4119.773412462313;4203.831846726127;4301.354796780477;4316.56834316569;3629.4756202394924;4102.589741025903;4249.787510624472;4287.20686672965;4296.8281268749215;4267.679924005712;4274.4229529013655;4280.828766849307;4282.843632219315;4284.314509678467;3224.226185715435;3684.6868016218655;3709.8330575123914;3709.9629003710156;3710.7959062251566;4742.668013239075;4747.382840230783;5257.579393648499;5313.415524292324;5500.367457742371;4816.349792778;4841.2496063110275;4853.39332583427;4871.220604703266;4886.975565122188;5047.141985862412;5610.859728506779;5811.27359080115;5932.139839723259;5989.3112292086535;3172.9682703172903;3365.3774051800497;3512.7892326460496;3966.186931678999;4032.697547683935;5253.821438029975;5822.7088645567555;6264.2796078451565;6340.44659058529;6359.20509936258;3659.76211546254;3889.688824894058;4088.550259471456;4094.5023535643236;4113.876583702494;3746.2882052409955;3998.6604487497016;4056.472658554428;4124.52567954683;4181.51912530063;4849.75751212438;4859.975700121548;4880.707157570557;4884.780184891689;4901.892186359828;5224.451432599599;5935.1987481690285;5949.464548190684;6068.48287928018;6076.392360763976;1040.7190058684114;1287.2726909296218;1377.6624726941964;1387.7502049630962;1401.9369128389246;772.0118248641747;820.9014918209853;847.8177191903646;856.8543347630917;861.5562985062697;4182.53992060873;4205.684573656988;4304.677149213821;4656.953430465652;4693.389859318267;4228.5982831630945;4389.978050109729;5000.74996250186;5096.719680417538;5109.74811171258;4253.788389765769;4640.5846216904165;4678.228092364721;4721.102990431807;4773.403324584427;2229.687843701883;2336.4742932839977;2449.3344525722323;2696.8786404611837;2720.4831082094734;4532.886677833015;4614.284785858197;5163.66436181647;5226.320578324793;5290.9735451322495;4899.755012249373;5141.018209438516;5206.401263854706;5210.921836172461;5299.894002119936;4459.910801784011;4657.417822772153;5213.400458947193;5223.921641175385;5231.163013917755;4371.846985355497;4538.72767633943;4584.845122792282;5093.414257360451;5149.892773053812;4624.1676498230645;5040.596752259884;5043.746783324808;5054.4187418447345;5123.231515272739;4400.877776167057;4672.486026537146;5042.773075211551;5121.8207362743615;5292.894142117213;3007.9548806768353;3518.5601799775027;3520.982395088008;3530.541029666127;3541.8937431877084;4304.892377690583;4446.888827779331;4564.425273280713;4731.195696731495;5857.301382598998;4691.812327506861;5616.949813318191;5882.647041177543;5902;5940.584159108821;5625.41231033729;5921.3782552831335;5939.881202376015;5946.375630558729;5976.013957696931;4676.415448068991;5307.734613269244;5497.2958382901425;5673.234113394639;5805.564582656233;4868.61051115917;5046.8709400170665;5233.790648374023;5344.984452953926;5382.735057261619;5022;5604.887902242014;5792.82621521355;5809.448102430262;5850.3857095006115 00:06:12 INFO - PROCESS | 1662 | |5;yui.html;594.215635361323;720.2257573108909;725.9999999999997;728.6320408193862;731.3746746531715;15120.621984450381;15441.768373474408;15739.370425182993;15933.283002264912;15979;14178.291085445739;14385.856141438582;14410.207438590873;14428.062175958556;14444.277786110679;682.3824438882795;839.5424493650975;861.7070196133313;865.9913400866005;867.7440155154213;817.0971076959963;837.7319257837495;849.1593322610602;854.6026097864492;855.3285670748463;839.345310657688;856.9571521423935;873.7946582553095;890.7060669978891;899.8200359928007;840.6301227459898;855.6876739989921;894.8926128864578;896.5069211933443;897.4211633496391;630.9936900631027;644.1529388853643;651.6871901487312;654.1496055128314;655.2628293170184;14888.330025148894;15058.924705376403;15132.773008404773;15226.162561059138;15239.847601524065;14472.276386180649;14532.782008269884;14557.70884582313;14761.483348082766;14913.850861491355;11881.702957426047;11926.761464770656;11932.105092118127;11998.220115692453;12003.399830008551;3755.267722794056;3765.661090501868;3768.9623103768886;3809.695224382043;3922.3920292354733;113896.43051784771;115555;118498.40750796277;118518;120235;10929.398883061507;11058.723531911766;11097.944510277397;11217.439128043728;11437.59968401102;23513.764862351156;23707.644385334235;23840.880795595913;23874.283771486884;23930;6567.868642627121;7885.369170466349;7936.444448888637;7979.082405523323;8054.476459030261;4253.766042867672;4270.679699022586;4350.934735978963;4369.519352871182;4439.66702497314;3604.116991337139;3720.925581488355;3728.422094575345;3728.9440658390145;3734.3464893643504;618.5608218165063;620.1658768955747;625.5258078883609;630.4609558827228;658.6838317607575;637.5823835387829;654.6383522273659;665.1117743561358;671.0806195512082;683.8187880211748;9043.819123617623;9165.526675786583;9181.678641247658;9318.627254909728;9415.529223538933;542.5849225342616;647.7409036385484;660.9636469994197;672.707372293054;678.128604742903;4493.236149854532;4616.515265897099;4629.375034370384;4630.050839577879;4640.21855090381;99369.5094573582;147181.26409367885;161819;163052.84735763402;169384.15307923628;97282;124766.12850807245;146335;147254;148137.25931370273;117471;119189;120670.79329206771;121699;124605.13092303622;8047.718329858427;8900.243479304332;8949.910500894908;8963.417377870417;9109.590068446803;65406.10185005668;65771;66203.99999999808;66725.66637166783;67896.66051669908;87473;95832;99540.00919981705;100620.49689751504;104330.47834760777;10930.01629853317;11205.271657342246;11240.156988225752;12045.156839021185;12143.939280303895;13746.587602371945;13899.583012509642;14837.035592686441;14985.850141498882;15262.313195905988;13534.93232533831;13559.457621695416;13754.449822007407;13883.444662213398;14393.92803035978;13615.65960850947;13873.653158671113;13973.371198296305;13980.440782368998;14167.291635417982;13093.869061309266;13325.200487970753;13466.528671496451;13491.932540337235;14188.233294507885;10513.316634418738;11126.88873111291;11180.440977951233;11311.490982905954;12108.878911210775;8129.105798362057;8607.655693772429;10230.874603793725;10928.117775764762;11500.654980350602 00:06:12 INFO - PROCESS | 1662 | __end_tp_report 00:06:12 INFO - PROCESS | 1662 | __start_cc_report 00:06:12 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,391 00:06:12 INFO - PROCESS | 1662 | __end_cc_report 00:06:12 INFO - PROCESS | 1662 | __startTimestamp1473231972316__endTimestamp 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | ------- Summary: start ------- 00:06:12 INFO - PROCESS | 1662 | Number of tests: 6 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5611.27 Median:5795.72 stddev:713.18 (12.3%) stddev-sans-first:689.99 00:06:12 INFO - PROCESS | 1662 | Values: 3207.7 3290.2 3313.0 3338.8 3365.8 5539.5 5569.1 5580.6 5651.4 5670.7 5457.2 5523.3 5582.2 5620.8 5663.7 3687.5 4978.2 5092.7 5188.6 5207.9 4169.6 4522.3 4652.8 4831.2 5348.8 5114.8 5187.5 5205.3 5207.1 5227.4 5160.0 5171.7 5180.6 5221.7 5280.9 4179.8 5127.6 5177.3 5208.0 5266.5 4394.3 5502.6 5569.2 5634.4 5659.1 5031.5 6165.9 6170.8 6180.4 6206.7 6242.1 6242.4 6250.4 6281.8 6283.7 4671.3 5593.1 5665.4 5719.3 5723.1 6281.1 6295.7 6296.4 6296.5 6331.8 6078.6 6085.6 6088.5 6093.8 6100.6 6055.2 6081.4 6111.8 6171.0 6211.2 5959.6 6048.4 6080.1 6121.1 6128.1 5123.8 6173.4 6183.9 6197.8 6198.9 4895.7 4898.2 4901.2 4903.1 4934.4 4198.2 4254.2 4273.5 4299.8 4429.4 5196.7 5629.4 5705.2 5711.8 5726.6 6203.9 6242.9 6243.6 6250.1 6275.9 5539.9 5571.0 5622.6 5651.0 5692.6 5268.3 5311.6 5321.6 5328.4 5329.8 6283.3 6313.4 6319.7 6322.4 6323.6 6060.6 6147.0 6158.1 6177.6 6244.1 6220.7 6270.6 6285.9 6290.7 6299.0 6142.7 6158.9 6186.8 6187.2 6201.8 6095.2 6140.5 6201.1 6211.6 6271.5 5941.6 6050.3 6072.8 6091.7 6106.6 4879.1 5756.3 6008.5 6016.5 6056.5 4415.0 5718.9 5795.7 5805.0 5957.3 6016.4 6039.8 6048.8 6059.1 6095.9 4864.2 5935.9 5943.5 5947.3 5960.1 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:69871.58 Median:39244.21 stddev:153936.83 (392.3%) stddev-sans-first:154280.55 00:06:12 INFO - PROCESS | 1662 | Values: 503.4 507.8 522.7 522.9 564.7 37597.6 39971.4 39983.0 40053.4 40108.2 40238.4 41034.2 41567.0 41760.0 41779.8 9444.4 9592.8 9674.9 9677.7 9746.0 9512.9 9586.4 9588.8 9613.0 9624.8 9616.2 9624.0 9639.5 9671.2 9684.0 9580.0 9636.3 9650.0 9653.4 9661.5 9456.0 9456.2 9469.2 9536.6 9571.4 39065.6 39154.4 39191.8 39296.6 39557.8 3081.6 3146.0 3244.5 3469.5 3859.1 30263.2 30580.0 31034.7 31624.5 31707.0 3025.6 3150.8 3191.8 3232.7 3300.4 496739.0 498784.0 499308.0 502026.0 503437.0 61851.0 62404.4 62559.0 62568.7 62659.0 33262.7 34120.5 64274.4 64856.0 65083.0 15789.6 16386.8 16423.5 16428.6 16481.2 12049.9 12490.9 12521.1 12548.4 12602.3 18924.7 18953.1 18958.7 18974.6 19204.6 6345.6 7179.3 7243.9 7275.6 8107.4 803.2 803.9 906.6 958.7 993.6 25818.9 29619.1 29671.1 29896.9 30324.7 1108.6 1136.4 1147.6 1167.5 1170.0 14992.2 15330.4 15426.1 15565.6 15703.6 542226.0 654222.0 662364.0 664135.0 665496.0 56216.0 56793.0 56875.0 56888.0 57176.7 1323.2 1330.9 1368.2 1370.4 1371.4 28973.3 28981.3 29100.6 29557.7 29675.0 48759.0 49039.5 49190.5 49229.2 49246.0 577068.0 578425.0 578691.0 579278.0 580413.0 25575.9 25654.5 26177.1 26269.3 26285.3 44783.3 45894.5 46437.8 46455.5 46893.5 29348.9 32902.2 37303.0 37577.8 38902.0 35826.7 37463.8 37789.8 38027.4 38848.8 37176.6 37550.8 38120.0 38155.6 38230.0 30780.5 30943.1 32842.5 33073.8 34347.5 23800.8 24153.6 24998.8 25427.1 25857.4 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33799.06 Median:3112.84 stddev:98401.76 (3161.2%) stddev-sans-first:98645.66 00:06:12 INFO - PROCESS | 1662 | Values: 304.5 367.6 372.8 375.8 392.9 19495.2 20358.7 20943.1 21301.1 21490.2 20088.5 20096.0 20437.5 20568.8 20785.9 5726.0 5812.3 6035.2 6104.4 6120.1 5932.6 6050.0 6053.4 6059.9 6069.8 5703.0 5912.7 5949.8 5954.3 6015.3 5286.9 5837.9 5901.7 5948.1 5972.2 4888.2 5141.2 5141.3 5147.4 5160.2 24642.6 24855.4 24921.9 25132.2 25595.2 2158.0 2218.4 2834.3 3089.8 3135.9 15101.2 15242.4 15356.5 15731.9 15909.0 1858.0 1858.2 1862.8 1880.3 1910.9 109311.5 110361.3 116873.0 119875.8 122507.0 8453.9 8998.3 9108.6 10226.9 10498.9 13135.7 13235.2 13296.2 13317.3 13324.1 7234.3 7892.4 8287.3 8349.5 8431.9 8247.2 8282.9 8358.7 8511.7 8525.4 5550.6 6062.5 6410.1 6542.2 6563.5 1678.5 1830.8 1838.9 1839.9 1840.2 754.5 876.3 939.6 981.0 1017.9 20416.3 20438.2 22036.4 22045.2 22083.7 929.6 942.8 947.5 995.9 1020.0 8367.7 10782.9 11398.6 11398.7 11593.8 583659.0 589120.0 596835.0 598215.0 600048.0 67552.0 104158.5 111188.9 118238.8 118543.0 949.5 978.7 985.9 1001.5 1005.6 18527.4 19179.4 19218.8 19715.0 19734.3 33396.0 49581.5 51120.7 51151.0 51902.5 53783.0 61975.1 69385.7 70726.6 75069.6 7643.5 7860.7 8053.4 8064.7 8254.9 13071.6 13166.1 13358.0 13417.5 13639.5 11247.8 11252.4 11324.7 11378.5 11589.4 9099.0 9421.3 9499.8 11223.6 11591.9 11278.7 11483.7 11518.4 11620.8 11885.0 6900.3 7353.3 7384.4 8008.7 8238.3 10697.3 10952.7 11249.8 11351.8 11436.9 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5612.65 Median:4866.16 stddev:3160.68 (65.0%) stddev-sans-first:3153.26 00:06:12 INFO - PROCESS | 1662 | Values: 1342.7 1499.1 1561.4 1716.6 1846.2 7986.0 8422.2 8451.2 8988.7 8998.2 7360.8 8492.6 8648.1 8717.3 8765.2 1978.3 2349.0 2363.9 2389.5 2395.2 2326.1 2393.0 2393.5 2395.3 2396.0 1828.6 2355.5 2376.0 2380.4 2392.2 2354.4 2361.2 2370.8 2371.3 2374.5 1948.8 2337.4 2339.8 2342.8 2356.6 8744.7 8834.7 8846.6 8852.5 8860.6 5256.7 5427.3 5438.2 5450.4 5451.8 3492.6 3558.9 3629.9 3692.4 3702.3 1055.3 1081.4 1081.6 1108.8 1109.2 11622.4 11788.5 11817.7 11917.6 11956.9 4614.8 4668.5 4815.2 4862.4 4870.0 8391.7 9850.8 9887.2 9913.3 9941.9 5309.9 5321.9 5330.3 5336.2 5337.6 4343.0 4743.0 4877.7 4914.5 4915.8 3886.3 3929.0 3970.4 4016.0 4049.9 1289.8 1315.2 1317.1 1319.2 1323.5 781.5 891.6 918.4 930.4 932.3 4695.4 5651.2 5777.8 5963.1 6037.2 945.7 965.6 970.7 979.1 990.4 3954.1 3956.2 3970.5 4094.5 4113.7 10407.5 10623.3 10721.6 10780.1 10787.2 10573.9 10701.6 10751.1 10773.6 10798.0 8016.7 8038.4 8086.3 8261.2 8279.8 5445.3 5647.2 5716.7 5735.5 5768.4 9943.2 9971.0 10046.9 10143.5 10150.1 7583.0 9569.6 9644.7 10226.3 10320.0 5388.9 6481.0 6608.1 6832.2 6949.9 8410.5 8498.4 8544.8 8549.4 8557.8 7093.7 7204.5 7223.2 7289.1 7299.1 7096.5 7107.9 7123.3 7140.2 7207.4 6566.7 6619.2 6629.0 6671.5 6672.0 4078.4 4640.0 4642.9 4666.0 4774.8 4287.6 4683.6 4791.0 4847.0 4962.3 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4430.73 Median:4828.80 stddev:1361.66 (28.2%) stddev-sans-first:1329.47 00:06:12 INFO - PROCESS | 1662 | Values: 285.9 287.0 294.6 301.5 302.1 5044.6 5542.9 5545.3 5644.4 5659.5 5091.5 5200.4 5392.6 5505.8 5616.9 3864.1 4221.7 4268.0 4275.7 4318.2 4099.9 4119.8 4203.8 4301.4 4316.6 3629.5 4102.6 4249.8 4287.2 4296.8 4267.7 4274.4 4280.8 4282.8 4284.3 3224.2 3684.7 3709.8 3710.0 3710.8 4742.7 4747.4 5257.6 5313.4 5500.4 4816.3 4841.2 4853.4 4871.2 4887.0 5047.1 5610.9 5811.3 5932.1 5989.3 3173.0 3365.4 3512.8 3966.2 4032.7 5253.8 5822.7 6264.3 6340.4 6359.2 3659.8 3889.7 4088.6 4094.5 4113.9 3746.3 3998.7 4056.5 4124.5 4181.5 4849.8 4860.0 4880.7 4884.8 4901.9 5224.5 5935.2 5949.5 6068.5 6076.4 1040.7 1287.3 1377.7 1387.8 1401.9 772.0 820.9 847.8 856.9 861.6 4182.5 4205.7 4304.7 4657.0 4693.4 4228.6 4390.0 5000.7 5096.7 5109.7 4253.8 4640.6 4678.2 4721.1 4773.4 2229.7 2336.5 2449.3 2696.9 2720.5 4532.9 4614.3 5163.7 5226.3 5291.0 4899.8 5141.0 5206.4 5210.9 5299.9 4459.9 4657.4 5213.4 5223.9 5231.2 4371.8 4538.7 4584.8 5093.4 5149.9 4624.2 5040.6 5043.7 5054.4 5123.2 4400.9 4672.5 5042.8 5121.8 5292.9 3008.0 3518.6 3521.0 3530.5 3541.9 4304.9 4446.9 4564.4 4731.2 5857.3 4691.8 5616.9 5882.6 5902.0 5940.6 5625.4 5921.4 5939.9 5946.4 5976.0 4676.4 5307.7 5497.3 5673.2 5805.6 4868.6 5046.9 5233.8 5345.0 5382.7 5022.0 5604.9 5792.8 5809.4 5850.4 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:25746.57 Median:3728.68 stddev:41691.22 (1118.1%) stddev-sans-first:41765.41 00:06:12 INFO - PROCESS | 1662 | Values: 594.2 720.2 726.0 728.6 731.4 15120.6 15441.8 15739.4 15933.3 15979.0 14178.3 14385.9 14410.2 14428.1 14444.3 682.4 839.5 861.7 866.0 867.7 817.1 837.7 849.2 854.6 855.3 839.3 857.0 873.8 890.7 899.8 840.6 855.7 894.9 896.5 897.4 631.0 644.2 651.7 654.1 655.3 14888.3 15058.9 15132.8 15226.2 15239.8 14472.3 14532.8 14557.7 14761.5 14913.9 11881.7 11926.8 11932.1 11998.2 12003.4 3755.3 3765.7 3769.0 3809.7 3922.4 113896.4 115555.0 118498.4 118518.0 120235.0 10929.4 11058.7 11097.9 11217.4 11437.6 23513.8 23707.6 23840.9 23874.3 23930.0 6567.9 7885.4 7936.4 7979.1 8054.5 4253.8 4270.7 4350.9 4369.5 4439.7 3604.1 3720.9 3728.4 3728.9 3734.3 618.6 620.2 625.5 630.5 658.7 637.6 654.6 665.1 671.1 683.8 9043.8 9165.5 9181.7 9318.6 9415.5 542.6 647.7 661.0 672.7 678.1 4493.2 4616.5 4629.4 4630.1 4640.2 99369.5 147181.3 161819.0 163052.8 169384.2 97282.0 124766.1 146335.0 147254.0 148137.3 117471.0 119189.0 120670.8 121699.0 124605.1 8047.7 8900.2 8949.9 8963.4 9109.6 65406.1 65771.0 66204.0 66725.7 67896.7 87473.0 95832.0 99540.0 100620.5 104330.5 10930.0 11205.3 11240.2 12045.2 12143.9 13746.6 13899.6 14837.0 14985.9 15262.3 13534.9 13559.5 13754.4 13883.4 14393.9 13615.7 13873.7 13973.4 13980.4 14167.3 13093.9 13325.2 13466.5 13491.9 14188.2 10513.3 11126.9 11180.4 11311.5 12108.9 8129.1 8607.7 10230.9 10928.1 11500.7 00:06:12 INFO - PROCESS | 1662 | -------- Summary: end -------- 00:06:12 INFO - PROCESS | 1662 | 00:06:12 INFO - TEST-INFO | 1662: exit 0 00:06:12 INFO - TEST-OK | dromaeo_css | took 1112804ms 00:06:12 INFO - TEST-START | kraken 00:06:12 INFO - operating with platform_type : mac_ 00:06:12 INFO - Initialising browser for kraken test... 00:06:12 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpEp03YQ/profile http://localhost:49271/getInfo.html) 00:06:15 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 00:06:15 INFO - PROCESS | 1671 | colorDepth:24 00:06:15 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 00:06:15 INFO - PROCESS | 1671 | __metrics 00:06:15 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 00:06:16 INFO - TEST-INFO | 1671: exit 0 00:06:16 INFO - Browser initialized. 00:06:16 INFO - Running cycle 1/1 for kraken test... 00:06:16 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/T/tmpEp03YQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:06:28 INFO - PROCESS | 1673 | RSS: Main: 243679232 00:06:28 INFO - PROCESS | 1673 | 00:06:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 00:06:32 INFO - PROCESS | 1673 | RSS: Main: 256516096 00:06:32 INFO - PROCESS | 1673 | 00:06:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 00:06:35 INFO - PROCESS | 1673 | RSS: Main: 265232384 00:06:35 INFO - PROCESS | 1673 | 00:06:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 00:06:39 INFO - PROCESS | 1673 | RSS: Main: 263778304 00:06:39 INFO - PROCESS | 1673 | 00:06:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 00:06:42 INFO - PROCESS | 1673 | RSS: Main: 265281536 00:06:42 INFO - PROCESS | 1673 | 00:06:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:06:44 INFO - PROCESS | 1673 | RSS: Main: 265474048 00:06:44 INFO - PROCESS | 1673 | 00:06:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 00:06:48 INFO - PROCESS | 1673 | RSS: Main: 265863168 00:06:48 INFO - PROCESS | 1673 | 00:06:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 00:06:51 INFO - PROCESS | 1673 | RSS: Main: 267296768 00:06:51 INFO - PROCESS | 1673 | 00:06:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 00:06:55 INFO - PROCESS | 1673 | RSS: Main: 267649024 00:06:55 INFO - PROCESS | 1673 | 00:06:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:06:57 INFO - PROCESS | 1673 | RSS: Main: 267022336 00:06:57 INFO - PROCESS | 1673 | 00:06:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:07:00 INFO - PROCESS | 1673 | RSS: Main: 267071488 00:07:00 INFO - PROCESS | 1673 | 00:07:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:07:03 INFO - PROCESS | 1673 | RSS: Main: 267468800 00:07:03 INFO - PROCESS | 1673 | 00:07:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:07:06 INFO - PROCESS | 1673 | RSS: Main: 267931648 00:07:06 INFO - PROCESS | 1673 | 00:07:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:07:10 INFO - PROCESS | 1673 | RSS: Main: 267788288 00:07:10 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 00:07:12 INFO - PROCESS | 1673 | RSS: Main: 269807616 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | __start_tp_report 00:07:12 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 00:07:12 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 00:07:12 INFO - PROCESS | 1673 | |i|pagename|runs| 00:07:12 INFO - PROCESS | 1673 | |0;ai-astar;108;89;97;108;171;148;167;120;116;136 00:07:12 INFO - PROCESS | 1673 | |1;audio-beat-detection;155;133;125;153;101;161;124;150;161;129 00:07:12 INFO - PROCESS | 1673 | |2;audio-dft;200;209;210;228;203;271;200;204;203;194 00:07:12 INFO - PROCESS | 1673 | |3;audio-fft;68;79;76;139;59;60;58;68;134;59 00:07:12 INFO - PROCESS | 1673 | |4;audio-oscillator;69;73;117;94;90;88;111;68;72;108 00:07:12 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;100;88;94;90;85;90;80;126;86 00:07:12 INFO - PROCESS | 1673 | |6;imaging-darkroom;97;153;130;96;95;94;96;98;132;94 00:07:12 INFO - PROCESS | 1673 | |7;imaging-desaturate;93;94;91;92;95;106;92;117;152;110 00:07:12 INFO - PROCESS | 1673 | |8;json-parse-financial;56;59;57;57;59;66;67;57;57;56 00:07:12 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;67;71;67;77;83;78;63;68;88;105 00:07:12 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;92;104;119;109;106;89;85;90;93;87 00:07:12 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;163;162;164;157;160;159;141;145;178;144 00:07:12 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;174;179;172;174;180;179;173;175;171;177 00:07:12 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;69;70;70;69;70;68;69;69;66;68 00:07:12 INFO - PROCESS | 1673 | __end_tp_report 00:07:12 INFO - PROCESS | 1673 | __start_cc_report 00:07:12 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,967 00:07:12 INFO - PROCESS | 1673 | __end_cc_report 00:07:12 INFO - PROCESS | 1673 | __startTimestamp1473232032419__endTimestamp 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | ------- Summary: start ------- 00:07:12 INFO - PROCESS | 1673 | Number of tests: 14 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:126.00 Median:157.50 stddev:28.45 (18.1%) stddev-sans-first:29.42 00:07:12 INFO - PROCESS | 1673 | Values: 108.0 89.0 97.0 108.0 171.0 148.0 167.0 120.0 116.0 136.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:151.50 stddev:19.84 (13.1%) stddev-sans-first:20.20 00:07:12 INFO - PROCESS | 1673 | Values: 155.0 133.0 125.0 153.0 101.0 161.0 124.0 150.0 161.0 129.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:212.20 Median:206.50 stddev:22.58 (10.9%) stddev-sans-first:23.51 00:07:12 INFO - PROCESS | 1673 | Values: 200.0 209.0 210.0 228.0 203.0 271.0 200.0 204.0 203.0 194.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:80.00 Median:64.00 stddev:30.67 (47.9%) stddev-sans-first:32.23 00:07:12 INFO - PROCESS | 1673 | Values: 68.0 79.0 76.0 139.0 59.0 60.0 58.0 68.0 134.0 59.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:72.50 stddev:18.38 (25.4%) stddev-sans-first:18.02 00:07:12 INFO - PROCESS | 1673 | Values: 69.0 73.0 117.0 94.0 90.0 88.0 111.0 68.0 72.0 108.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:88.00 stddev:12.86 (14.6%) stddev-sans-first:13.53 00:07:12 INFO - PROCESS | 1673 | Values: 88.0 100.0 88.0 94.0 90.0 85.0 90.0 80.0 126.0 86.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:95.50 stddev:21.48 (22.5%) stddev-sans-first:22.38 00:07:12 INFO - PROCESS | 1673 | Values: 97.0 153.0 130.0 96.0 95.0 94.0 96.0 98.0 132.0 94.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:92.00 stddev:19.07 (20.7%) stddev-sans-first:19.79 00:07:12 INFO - PROCESS | 1673 | Values: 93.0 94.0 91.0 92.0 95.0 106.0 92.0 117.0 152.0 110.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:59.10 Median:58.00 stddev:4.04 (7.0%) stddev-sans-first:4.13 00:07:12 INFO - PROCESS | 1673 | Values: 56.0 59.0 57.0 57.0 59.0 66.0 67.0 57.0 57.0 56.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:74.00 stddev:12.71 (17.2%) stddev-sans-first:12.99 00:07:12 INFO - PROCESS | 1673 | Values: 67.0 71.0 67.0 77.0 83.0 78.0 63.0 68.0 88.0 105.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:97.40 Median:88.00 stddev:11.33 (12.9%) stddev-sans-first:11.84 00:07:12 INFO - PROCESS | 1673 | Values: 92.0 104.0 119.0 109.0 106.0 89.0 85.0 90.0 93.0 87.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:161.00 stddev:11.22 (7.0%) stddev-sans-first:11.70 00:07:12 INFO - PROCESS | 1673 | Values: 163.0 162.0 164.0 157.0 160.0 159.0 141.0 145.0 178.0 144.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:176.00 stddev:3.17 (1.8%) stddev-sans-first:3.32 00:07:12 INFO - PROCESS | 1673 | Values: 174.0 179.0 172.0 174.0 180.0 179.0 173.0 175.0 171.0 177.0 00:07:12 INFO - PROCESS | 1673 | 00:07:12 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 00:07:12 INFO - PROCESS | 1673 | Values: 69.0 70.0 70.0 69.0 70.0 68.0 69.0 69.0 66.0 68.0 00:07:12 INFO - PROCESS | 1673 | -------- Summary: end -------- 00:07:12 INFO - PROCESS | 1673 | 00:07:13 INFO - TEST-INFO | 1673: exit 0 00:07:13 INFO - TEST-OK | kraken | took 60261ms 00:07:13 INFO - SUITE-END | took 1173s 00:07:13 INFO - Completed test suite (00:19:33) 00:07:13 INFO - javascript benchmark 00:07:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7272.551105080262, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3207.7433805295577, 3290.161008942721, 3312.9503057454126, 3338.7996720196775, 3365.8148801815905, 5539.473749993749, 5569.053260945639, 5580.553555715544, 5651.350094739096, 5670.659760414378, 5457.2359869618185, 5523.309586301712, 5582.190582365554, 5620.831375058745, 5663.66018038918, 3687.483752274684, 4978.153713868651, 5092.694438333693, 5188.558972487343, 5207.86980325493, 4169.583041695836, 4522.29904364824, 4652.767361631905, 4831.154547954112, 5348.812791552296, 5114.84655460333, 5187.48125187482, 5205.349331333583, 5207.0887594670785, 5227.424983251839, 5159.974200128975, 5171.689698618095, 5180.6373553851245, 5221.71280579568, 5280.894382112337, 4179.770112643803, 5127.564157046618, 5177.275181474596, 5208.0, 5266.499682530153, 4394.318880573516, 5502.58730595203, 5569.248151499536, 5634.352049514277, 5659.122835960433, 5031.52200540948, 6165.907511387333, 6170.84572885677, 6180.443760061616, 6206.6586337751405, 6242.09489624002, 6242.406971337716, 6250.4374606285655, 6281.780137695158, 6283.7172327244925, 4671.346011558385, 5593.098346562188, 5665.433456654301, 5719.25649665545, 5723.141528770676, 6281.057841323838, 6295.653739044396, 6296.401841825019, 6296.464800492009, 6331.778387756406, 6078.6048906820915, 6085.604435711664, 6088.543359248074, 6093.847653808691, 6100.633961962297, 6055.212822333068, 6081.391860813883, 6111.804454079419, 6171.043488259327, 6211.223597050369, 5959.642421454727, 6048.395160484004, 6080.0575910733905, 6121.056693971993, 6128.080787881764, 5123.820666276662, 6173.41352571505, 6183.938160618336, 6197.845053873599, 6198.907016394667, 4895.7062576245535, 4898.21628539432, 4901.166801643729, 4903.141950158708, 4934.383202099738, 4198.202341555095, 4254.234237837157, 4273.465816772903, 4299.806508707115, 4429.402030725875, 5196.714180720097, 5629.408912064257, 5705.2012718219485, 5711.771529138871, 5726.627769194989, 6203.937960620427, 6242.937570624326, 6243.562950593415, 6250.062490626351, 6275.874482510324, 5539.8892022160135, 5570.972145139248, 5622.606417550814, 5651.039323315046, 5692.601517893708, 5268.288781014514, 5311.628186027018, 5321.600879934021, 5328.387235467893, 5329.813456529003, 6283.3088360281245, 6313.368663133833, 6319.747210111636, 6322.430981211713, 6323.557350985571, 6060.606060606046, 6147.0, 6158.107074474266, 6177.567570270038, 6244.094606281973, 6220.657863817533, 6270.592411493239, 6285.937140628535, 6290.716917738804, 6299.023651334051, 6142.692865356625, 6158.876822463436, 6186.81439556814, 6187.16473276102, 6201.844953876189, 6095.177151084724, 6140.478059365004, 6201.10084037821, 6211.596246243979, 6271.529635277281, 5941.643501389931, 6050.30421501533, 6072.787452439144, 6091.695415229309, 6106.572539922163, 4879.146149423766, 5756.30924289088, 6008.519318454601, 6016.518678505797, 6056.545759068175, 4415.006623509685, 5718.857028574319, 5795.724940513081, 5805.042168042226, 5957.255343082114, 6016.42843929826, 6039.849003774941, 6048.758049678139, 6059.0608455689435, 6095.939040609537, 4864.197407427876, 5935.910961335497, 5943.52451803846, 5947.3457919627945, 5960.1357803117435], "value": 5565.833800251049, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.39341093981733, 507.8273387048404, 522.7203446156307, 522.8966879883446, 564.7486868343584, 37597.62402375979, 39971.400428993555, 39983.0, 40053.399199012005, 40108.19783604326, 40238.396424053666, 41034.179316413654, 41566.96082597937, 41760.0, 41779.79110104445, 9444.433333999965, 9592.760180995496, 9674.854877176847, 9677.661281855137, 9745.951270243639, 9512.857307140399, 9586.376885502421, 9588.808223835518, 9613.038696130401, 9624.80750384992, 9616.230701543895, 9624.0, 9639.469829159394, 9671.162479128943, 9684.0, 9579.994100619439, 9636.325457217998, 9650.0, 9653.372530785477, 9661.46861922594, 9456.0, 9456.196223321025, 9469.195118414944, 9536.570854311503, 9571.37786043905, 39065.60934390648, 39154.412683809765, 39191.804040979616, 39296.607033929584, 39557.80221098905, 3081.5531747896653, 3146.0, 3244.545763593075, 3469.4991810195115, 3859.0931131184147, 30263.243418914484, 30580.0, 31034.689653103407, 31624.525632115536, 31707.0, 3025.6369235691636, 3150.82670453125, 3191.789430970753, 3232.7253726144017, 3300.389427955836, 496739.0, 498784.0, 499308.0, 502026.0, 503437.0, 61851.0, 62404.375956240765, 62559.0, 62568.68715656484, 62659.0, 33262.66737332644, 34120.48819267713, 64274.35725642777, 64856.027159592646, 65083.02375464372, 15789.605259868595, 16386.75419868703, 16423.507294781175, 16428.589285267964, 16481.17594120289, 12049.879501204876, 12490.937545312216, 12521.123521353418, 12548.372581370895, 12602.306873121883, 18924.71612925807, 18953.147108380155, 18958.71561926572, 18974.620507590047, 19204.615907681768, 6345.555811093272, 7179.317964793337, 7243.855122897513, 7275.636218189069, 8107.4324797263625, 803.2449497472358, 803.8593246181895, 906.5875026862761, 958.6500927161675, 993.5578667492896, 25818.87090564535, 29619.111426657233, 29671.109866704035, 29896.850515747283, 30324.69675303263, 1108.634150730257, 1136.4431428600092, 1147.5868687272575, 1167.4688016952266, 1169.9645813454983, 14992.175430351435, 15330.386784528493, 15426.074435533905, 15565.610859728371, 15703.580432187742, 542226.0, 654222.0, 662364.0, 664135.0, 665496.0, 56216.0, 56793.0, 56875.0, 56888.0, 57176.71411642915, 1323.1730168644597, 1330.946762129504, 1368.2406264523086, 1370.4381203706432, 1371.376023909139, 28973.275668108465, 28981.27546811262, 29100.563491547222, 29557.704422956354, 29675.0, 48759.0, 49039.50960490349, 49190.508094918594, 49229.184995601514, 49246.0, 577068.0, 578425.0, 578691.0, 579278.0, 580413.0, 25575.872120639277, 25654.486910262065, 26177.083802066838, 26269.34326641849, 26285.34286642849, 44783.32825007693, 45894.541054589026, 46437.76781116073, 46455.53544464647, 46893.53106468892, 29348.853255733586, 32902.177445564055, 37303.0, 37577.81211093927, 38902.0, 35826.74606388764, 37463.81268093642, 37789.81105094457, 38027.42958855675, 38848.805755971036, 37176.62823371732, 37550.81224593859, 38120.04699882525, 38155.618443815205, 38230.0, 30780.53829192519, 30943.0717078488, 32842.50736238911, 33073.83463082669, 34347.4847877287, 23800.761992379856, 24153.637695434933, 24998.75001249964, 25427.11005114812, 25857.353566160997], "value": 19665.29188373942, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.4838997898563, 367.590136997248, 372.79496277047616, 375.8477816484325, 392.8919547124542, 19495.220191192355, 20358.69461958068, 20943.05756240969, 21301.14795408186, 21490.247841325556, 20088.49778755532, 20096.0, 20437.48906277344, 20568.794312056874, 20785.896070519662, 5726.026575482178, 5812.3025236971625, 6035.155078289021, 6104.389561043905, 6120.1125836753645, 5932.644041357532, 6050.001749711314, 6053.365591290371, 6059.878802423949, 6069.848253793646, 5702.971485142569, 5912.733926973275, 5949.8215053548465, 5954.285485741717, 6015.278166620012, 5286.94261147772, 5837.941620583782, 5901.704914754245, 5948.107783832416, 5972.163897054415, 4888.217885138396, 5141.2288156776785, 5141.280220769096, 5147.356580427447, 5160.174372100483, 24642.630360544605, 24855.378615534577, 24921.87539062311, 25132.230165475143, 25595.232143035737, 2158.0, 2218.378853920905, 2834.3419099826624, 3089.7991630544166, 3135.8745650173964, 15101.244937753178, 15242.39030438781, 15356.539303820904, 15731.921340393339, 15908.965917215344, 1857.9907100464545, 1858.2009735813594, 1862.8416584590327, 1880.2666959885655, 1910.8757930734457, 109311.45344273387, 110361.34457983136, 116873.0, 119875.80124198821, 122507.0, 8453.915460845436, 8998.325125615605, 9108.59011344491, 10226.948865255627, 10498.895011049792, 13135.737285254241, 13235.213246211832, 13296.202227866359, 13317.33413329349, 13324.067315287837, 7234.276572342724, 7892.368610511146, 8287.254147126787, 8349.457285276438, 8431.915680843113, 8247.175282471824, 8282.875756863652, 8358.749237522885, 8511.702090426805, 8525.360597955178, 5550.555955523549, 6062.484378905274, 6410.1154480483965, 6542.182227221598, 6563.540552161303, 1678.4628918746128, 1830.8352248297717, 1838.9172487238106, 1839.8804077735172, 1840.2087102546034, 754.5171090502083, 876.2683159561838, 939.572494514994, 980.9509524523745, 1017.9083882450615, 20416.285430009877, 20438.182472701224, 22036.449088772588, 22045.228417005328, 22083.66874496884, 929.6327950459665, 942.7690215897012, 947.5309721687809, 995.8904520502739, 1019.954102065394, 8367.748967530984, 10782.892171078345, 11398.58079529396, 11398.715032124263, 11593.826092608617, 583659.0, 589120.0, 596835.0, 598215.0, 600048.0, 67552.0, 104158.47920760348, 111188.88811111785, 118238.8176118245, 118543.0, 949.5062567464879, 978.6770365779419, 985.9457729824785, 1001.4992503748126, 1005.6480231918916, 18527.35154269648, 19179.424617261506, 19218.846869187895, 19715.0, 19734.30929917446, 33396.0, 49581.50418495769, 51120.74439627778, 51151.0, 51902.480975191276, 53783.0, 61975.07037394533, 69385.65307173633, 70726.64636676783, 75069.6246518764, 7643.464957452925, 7860.724874629361, 8053.436259461781, 8064.717734879251, 8254.917450825415, 13071.647103521458, 13166.144200626928, 13358.0, 13417.463301467831, 13639.454421823017, 11247.7750444989, 11252.381119038204, 11324.716882077684, 11378.54485820558, 11589.420528973349, 9099.0, 9421.304165250322, 9499.810003800023, 11223.60717374888, 11591.884081159418, 11278.718032049264, 11483.655490335303, 11518.424078796193, 11620.767584648429, 11885.0, 6900.3444672756, 7353.301436363537, 7384.446166537424, 8008.719694810887, 8238.299744521782, 10697.304675196086, 10952.671419857417, 11249.831252531056, 11351.772964540498, 11436.885631143583], "value": 9541.795917210384, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.6911810283634, 1499.0555949751658, 1561.3988614383459, 1716.5622766194626, 1846.169223849268, 7985.9600701996405, 8422.241998220159, 8451.239388455038, 8988.68539601115, 8998.23515001225, 7360.815979600499, 8492.575371231445, 8648.13518648135, 8717.259032982189, 8765.211130998208, 1978.307592342683, 2349.001674288429, 2363.9409014774637, 2389.498205376873, 2395.2215529952746, 2326.139328448468, 2392.982982232544, 2393.545226406986, 2395.269442819948, 2396.0176327705644, 1828.5611453251256, 2355.5171189906027, 2375.9643605345846, 2380.392999785061, 2392.1866565367773, 2354.3878591566145, 2361.244401791427, 2370.798482129004, 2371.312319427364, 2374.536965291769, 1948.824605785486, 2337.415646088478, 2339.836211465198, 2342.7657234276435, 2356.599378105726, 8744.650213991432, 8834.69078582253, 8846.646134154626, 8852.51311177885, 8860.601272942733, 5256.7371631418655, 5427.294451721291, 5438.238646589481, 5450.373207081197, 5451.809186678487, 3492.6029588164683, 3558.87543935961, 3629.891103266906, 3692.3538380783257, 3702.2965636528975, 1055.3404122423485, 1081.3728037738092, 1081.556561809662, 1108.7782443511248, 1109.1625822504038, 11622.418879056013, 11788.528458861636, 11817.704557386049, 11917.582884599084, 11956.940215298955, 4614.838480653161, 4668.486466488684, 4815.2054910939305, 4862.367892173995, 4869.9513004870205, 8391.748247552583, 9850.80298394028, 9887.209023278121, 9913.256505762096, 9941.900580994243, 5309.8938021239355, 5321.893562128813, 5330.2804121443105, 5336.226247194135, 5337.599680024014, 4342.978285108554, 4742.976285118552, 4877.6829506081995, 4914.483979182134, 4915.778789954461, 3886.3393223152134, 3929.0373858404873, 3970.4242884781543, 4015.9799201003807, 4049.919001619951, 1289.8323217981601, 1315.1845855569609, 1317.0912070671206, 1319.182107093608, 1323.483841301893, 781.5427974634789, 891.5542228885557, 918.3846822628855, 930.3627015494408, 932.2681694869631, 4695.436547614308, 5651.237082993825, 5777.768889244468, 5963.135345374896, 6037.245344331958, 945.7304668169538, 965.5896244096231, 970.6505657963128, 979.0747743382577, 990.4305024610877, 3954.1103251768122, 3956.208758248362, 3970.4838371011583, 4094.529129150126, 4113.650339721157, 10407.479626018669, 10623.256372054037, 10721.624743133953, 10780.083692886192, 10787.190960677824, 10573.894261057445, 10701.571937122584, 10751.139908807276, 10773.569057237622, 10798.028177464066, 8016.71941482057, 8038.437309388403, 8086.312663423557, 8261.17388261181, 8279.840822284885, 5445.264889239902, 5647.20939068539, 5716.685582292847, 5735.512481439125, 5768.423157684198, 9943.154831839085, 9970.950145249517, 10046.949765251127, 10143.492825358555, 10150.13723833453, 7583.0, 9569.569369378256, 9644.662436814679, 10226.28416010872, 10320.019598138166, 5388.865278367915, 6480.967595161994, 6608.1079054328975, 6832.248452670302, 6949.895751563832, 8410.453320534145, 8498.447600905922, 8544.786380340542, 8549.358798090292, 8557.786055348666, 7093.716251349888, 7204.4956853019785, 7223.169335526271, 7289.088863892013, 7299.087614048244, 7096.53872498286, 7107.893381599383, 7123.287671233043, 7140.178879428932, 7207.387372073433, 6566.704498297684, 6619.238787539494, 6629.0, 6671.46628269747, 6671.966640166962, 4078.4494093297026, 4639.976800115978, 4642.86071417858, 4665.953340466552, 4774.785134669018, 4287.614114729689, 4683.555062269079, 4790.976045119752, 4847.0, 4962.33008543842], "value": 4526.198164428642, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.8613572417377, 287.0369908955455, 294.61405558718076, 301.4829567292095, 302.14794280130013, 5044.646874718771, 5542.916856247154, 5545.306836645419, 5644.435556444359, 5659.490645841878, 5091.541761241488, 5200.361886005906, 5392.622516423852, 5505.807296744605, 5616.9157462638095, 3864.087276797989, 4221.746695198282, 4268.018355778173, 4275.700700950934, 4318.2227199104145, 4099.918001639965, 4119.773412462313, 4203.831846726127, 4301.354796780477, 4316.56834316569, 3629.4756202394924, 4102.589741025903, 4249.787510624472, 4287.20686672965, 4296.8281268749215, 4267.679924005712, 4274.4229529013655, 4280.828766849307, 4282.843632219315, 4284.314509678467, 3224.226185715435, 3684.6868016218655, 3709.8330575123914, 3709.9629003710156, 3710.7959062251566, 4742.668013239075, 4747.382840230783, 5257.579393648499, 5313.415524292324, 5500.367457742371, 4816.349792778, 4841.2496063110275, 4853.39332583427, 4871.220604703266, 4886.975565122188, 5047.141985862412, 5610.859728506779, 5811.27359080115, 5932.139839723259, 5989.3112292086535, 3172.9682703172903, 3365.3774051800497, 3512.7892326460496, 3966.186931678999, 4032.697547683935, 5253.821438029975, 5822.7088645567555, 6264.2796078451565, 6340.44659058529, 6359.20509936258, 3659.76211546254, 3889.688824894058, 4088.550259471456, 4094.5023535643236, 4113.876583702494, 3746.2882052409955, 3998.6604487497016, 4056.472658554428, 4124.52567954683, 4181.51912530063, 4849.75751212438, 4859.975700121548, 4880.707157570557, 4884.780184891689, 4901.892186359828, 5224.451432599599, 5935.1987481690285, 5949.464548190684, 6068.48287928018, 6076.392360763976, 1040.7190058684114, 1287.2726909296218, 1377.6624726941964, 1387.7502049630962, 1401.9369128389246, 772.0118248641747, 820.9014918209853, 847.8177191903646, 856.8543347630917, 861.5562985062697, 4182.53992060873, 4205.684573656988, 4304.677149213821, 4656.953430465652, 4693.389859318267, 4228.5982831630945, 4389.978050109729, 5000.74996250186, 5096.719680417538, 5109.74811171258, 4253.788389765769, 4640.5846216904165, 4678.228092364721, 4721.102990431807, 4773.403324584427, 2229.687843701883, 2336.4742932839977, 2449.3344525722323, 2696.8786404611837, 2720.4831082094734, 4532.886677833015, 4614.284785858197, 5163.66436181647, 5226.320578324793, 5290.9735451322495, 4899.755012249373, 5141.018209438516, 5206.401263854706, 5210.921836172461, 5299.894002119936, 4459.910801784011, 4657.417822772153, 5213.400458947193, 5223.921641175385, 5231.163013917755, 4371.846985355497, 4538.72767633943, 4584.845122792282, 5093.414257360451, 5149.892773053812, 4624.1676498230645, 5040.596752259884, 5043.746783324808, 5054.4187418447345, 5123.231515272739, 4400.877776167057, 4672.486026537146, 5042.773075211551, 5121.8207362743615, 5292.894142117213, 3007.9548806768353, 3518.5601799775027, 3520.982395088008, 3530.541029666127, 3541.8937431877084, 4304.892377690583, 4446.888827779331, 4564.425273280713, 4731.195696731495, 5857.301382598998, 4691.812327506861, 5616.949813318191, 5882.647041177543, 5902.0, 5940.584159108821, 5625.41231033729, 5921.3782552831335, 5939.881202376015, 5946.375630558729, 5976.013957696931, 4676.415448068991, 5307.734613269244, 5497.2958382901425, 5673.234113394639, 5805.564582656233, 4868.61051115917, 5046.8709400170665, 5233.790648374023, 5344.984452953926, 5382.735057261619, 5022.0, 5604.887902242014, 5792.82621521355, 5809.448102430262, 5850.3857095006115], "value": 3991.707130205778, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.215635361323, 720.2257573108909, 725.9999999999997, 728.6320408193862, 731.3746746531715, 15120.621984450381, 15441.768373474408, 15739.370425182993, 15933.283002264912, 15979.0, 14178.291085445739, 14385.856141438582, 14410.207438590873, 14428.062175958556, 14444.277786110679, 682.3824438882795, 839.5424493650975, 861.7070196133313, 865.9913400866005, 867.7440155154213, 817.0971076959963, 837.7319257837495, 849.1593322610602, 854.6026097864492, 855.3285670748463, 839.345310657688, 856.9571521423935, 873.7946582553095, 890.7060669978891, 899.8200359928007, 840.6301227459898, 855.6876739989921, 894.8926128864578, 896.5069211933443, 897.4211633496391, 630.9936900631027, 644.1529388853643, 651.6871901487312, 654.1496055128314, 655.2628293170184, 14888.330025148894, 15058.924705376403, 15132.773008404773, 15226.162561059138, 15239.847601524065, 14472.276386180649, 14532.782008269884, 14557.70884582313, 14761.483348082766, 14913.850861491355, 11881.702957426047, 11926.761464770656, 11932.105092118127, 11998.220115692453, 12003.399830008551, 3755.267722794056, 3765.661090501868, 3768.9623103768886, 3809.695224382043, 3922.3920292354733, 113896.43051784771, 115555.0, 118498.40750796277, 118518.0, 120235.0, 10929.398883061507, 11058.723531911766, 11097.944510277397, 11217.439128043728, 11437.59968401102, 23513.764862351156, 23707.644385334235, 23840.880795595913, 23874.283771486884, 23930.0, 6567.868642627121, 7885.369170466349, 7936.444448888637, 7979.082405523323, 8054.476459030261, 4253.766042867672, 4270.679699022586, 4350.934735978963, 4369.519352871182, 4439.66702497314, 3604.116991337139, 3720.925581488355, 3728.422094575345, 3728.9440658390145, 3734.3464893643504, 618.5608218165063, 620.1658768955747, 625.5258078883609, 630.4609558827228, 658.6838317607575, 637.5823835387829, 654.6383522273659, 665.1117743561358, 671.0806195512082, 683.8187880211748, 9043.819123617623, 9165.526675786583, 9181.678641247658, 9318.627254909728, 9415.529223538933, 542.5849225342616, 647.7409036385484, 660.9636469994197, 672.707372293054, 678.128604742903, 4493.236149854532, 4616.515265897099, 4629.375034370384, 4630.050839577879, 4640.21855090381, 99369.5094573582, 147181.26409367885, 161819.0, 163052.84735763402, 169384.15307923628, 97282.0, 124766.12850807245, 146335.0, 147254.0, 148137.25931370273, 117471.0, 119189.0, 120670.79329206771, 121699.0, 124605.13092303622, 8047.718329858427, 8900.243479304332, 8949.910500894908, 8963.417377870417, 9109.590068446803, 65406.10185005668, 65771.0, 66203.99999999808, 66725.66637166783, 67896.66051669908, 87473.0, 95832.0, 99540.00919981705, 100620.49689751504, 104330.47834760777, 10930.01629853317, 11205.271657342246, 11240.156988225752, 12045.156839021185, 12143.939280303895, 13746.587602371945, 13899.583012509642, 14837.035592686441, 14985.850141498882, 15262.313195905988, 13534.93232533831, 13559.457621695416, 13754.449822007407, 13883.444662213398, 14393.92803035978, 13615.65960850947, 13873.653158671113, 13973.371198296305, 13980.440782368998, 14167.291635417982, 13093.869061309266, 13325.200487970753, 13466.528671496451, 13491.932540337235, 14188.233294507885, 10513.316634418738, 11126.88873111291, 11180.440977951233, 11311.490982905954, 12108.878911210775, 8129.105798362057, 8607.655693772429, 10230.874603793725, 10928.117775764762, 11500.654980350602], "value": 7840.0754936702915, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 89.0, 97.0, 108.0, 171.0, 148.0, 167.0, 120.0, 116.0, 136.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 133.0, 125.0, 153.0, 101.0, 161.0, 124.0, 150.0, 161.0, 129.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 209.0, 210.0, 228.0, 203.0, 271.0, 200.0, 204.0, 203.0, 194.0], "value": 212.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 79.0, 76.0, 139.0, 59.0, 60.0, 58.0, 68.0, 134.0, 59.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 117.0, 94.0, 90.0, 88.0, 111.0, 68.0, 72.0, 108.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 100.0, 88.0, 94.0, 90.0, 85.0, 90.0, 80.0, 126.0, 86.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 153.0, 130.0, 96.0, 95.0, 94.0, 96.0, 98.0, 132.0, 94.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 91.0, 92.0, 95.0, 106.0, 92.0, 117.0, 152.0, 110.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 57.0, 57.0, 59.0, 66.0, 67.0, 57.0, 57.0, 56.0], "value": 59.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 67.0, 77.0, 83.0, 78.0, 63.0, 68.0, 88.0, 105.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 104.0, 119.0, 109.0, 106.0, 89.0, 85.0, 90.0, 93.0, 87.0], "value": 97.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 164.0, 157.0, 160.0, 159.0, 141.0, 145.0, 178.0, 144.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 179.0, 172.0, 174.0, 180.0, 179.0, 173.0, 175.0, 171.0, 177.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 69.0, 70.0, 68.0, 69.0, 69.0, 66.0, 68.0], "value": 68.8, "unit": "score"}]}]} 00:07:13 INFO - Return code: 0 00:07:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:07:13 ERROR - # TBPL SUCCESS # 00:07:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:07:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:07:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:07:13 INFO - 2016-09-07 00:07:13.731 screenresolution[1678:13238] starting screenresolution argv=screenresolution get 00:07:13 INFO - 2016-09-07 00:07:13.733 screenresolution[1678:13238] Display 0: 1600x1200x32@60 00:07:13 INFO - 2016-09-07 00:07:13.739 screenresolution[1679:13242] starting screenresolution argv=screenresolution list 00:07:13 INFO - Available Modes on Display 0 00:07:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:07:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:07:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:07:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:07:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:07:13 INFO - Intel Iris: 00:07:13 INFO - Chipset Model: Intel Iris 00:07:13 INFO - Type: GPU 00:07:13 INFO - Bus: Built-In 00:07:13 INFO - VRAM (Dynamic, Max): 1536 MB 00:07:13 INFO - Vendor: Intel (0x8086) 00:07:13 INFO - Device ID: 0x0a2e 00:07:13 INFO - Revision ID: 0x0009 00:07:13 INFO - Displays: 00:07:13 INFO - Display: 00:07:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:07:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:07:13 INFO - Display Serial Number: 1600x1200 60 00:07:13 INFO - Main Display: Yes 00:07:13 INFO - Mirror: Off 00:07:13 INFO - Online: Yes 00:07:13 INFO - Rotation: Supported 00:07:13 INFO - Return code: 0 00:07:13 INFO - Running post-action listener: _resource_record_post_action 00:07:13 INFO - [mozharness: 2016-09-07 07:07:13.976340Z] Finished run-tests step (success) 00:07:13 INFO - Running post-run listener: _resource_record_post_run 00:07:14 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 39148544; Write bytes: 715908608; Read time: 552; Write time: 2366 00:07:14 INFO - TinderboxPrint: CPU usage
54.7% 00:07:14 INFO - TinderboxPrint: I/O read bytes / time
39,148,544 / 552 00:07:14 INFO - TinderboxPrint: I/O write bytes / time
715,908,608 / 2,366 00:07:14 INFO - TinderboxPrint: CPU idle
2,190.8 (45.3%) 00:07:14 INFO - TinderboxPrint: CPU system
982.0 (20.3%) 00:07:14 INFO - TinderboxPrint: CPU user
1,660.9 (34.4%) 00:07:14 INFO - TinderboxPrint: Swap in / out
439,177,216 / 0 00:07:14 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404580864; Write bytes: 399017472; Read time: 29732; Write time: 735 00:07:14 INFO - run-tests - Wall time: 1174s; CPU: 55.0%; Read bytes: 35527680; Write bytes: 306143232; Read time: 523; Write time: 1602 00:07:14 INFO - Running post-run listener: _upload_blobber_files 00:07:14 INFO - Blob upload gear active. 00:07:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:07:14 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 00:07:14 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:07:14 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:07:14 INFO - (blobuploader) - INFO - Open directory for files ... 00:07:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:07:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:07:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:16 INFO - (blobuploader) - INFO - Done attempting. 00:07:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:07:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:07:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:17 INFO - (blobuploader) - INFO - Done attempting. 00:07:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:07:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:07:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:07:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:07:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:07:20 INFO - (blobuploader) - INFO - Done attempting. 00:07:20 INFO - (blobuploader) - INFO - Iteration through files over. 00:07:20 INFO - Return code: 0 00:07:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:07:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:07:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3eee97076d502959e91fd043f9dbfeb373f6a5198bb76968c38fa2385e0780d6768f32da304ebcd87e96c8c8024083ca575c3d97146c3daf4b1a479ac091cc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4473485dc053057ee5b97da5057d64055cc5dbe5a811dce3da13842ad8274fab4d14a5371f4619cc42ec77b0b9c3a99b8d6c4f100879e8f8a77c67a58b1fc194", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f3b47375331a65361e31e2d300364ce52b5314811221b626425772191cc54a5782ab387a9f558e99d8eb4d91995358aee1b3acfd3bd005f4f835f264b0944ff"} 00:07:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:07:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:07:20 INFO - Contents: 00:07:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3eee97076d502959e91fd043f9dbfeb373f6a5198bb76968c38fa2385e0780d6768f32da304ebcd87e96c8c8024083ca575c3d97146c3daf4b1a479ac091cc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4473485dc053057ee5b97da5057d64055cc5dbe5a811dce3da13842ad8274fab4d14a5371f4619cc42ec77b0b9c3a99b8d6c4f100879e8f8a77c67a58b1fc194", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f3b47375331a65361e31e2d300364ce52b5314811221b626425772191cc54a5782ab387a9f558e99d8eb4d91995358aee1b3acfd3bd005f4f835f264b0944ff"} 00:07:20 INFO - Running post-run listener: copy_logs_to_upload_dir 00:07:20 INFO - Copying logs to upload dir... 00:07:20 INFO - mkdir: /builds/slave/test/build/upload/logs 00:07:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.478202 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-07 00:07:20.093873) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 00:07:20.097850) ========= 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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3eee97076d502959e91fd043f9dbfeb373f6a5198bb76968c38fa2385e0780d6768f32da304ebcd87e96c8c8024083ca575c3d97146c3daf4b1a479ac091cc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4473485dc053057ee5b97da5057d64055cc5dbe5a811dce3da13842ad8274fab4d14a5371f4619cc42ec77b0b9c3a99b8d6c4f100879e8f8a77c67a58b1fc194", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f3b47375331a65361e31e2d300364ce52b5314811221b626425772191cc54a5782ab387a9f558e99d8eb4d91995358aee1b3acfd3bd005f4f835f264b0944ff"} build_url:https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011061 build_url: 'https://queue.taskcluster.net/v1/task/FtCm2RM1TsekYX8-zNcnGQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3eee97076d502959e91fd043f9dbfeb373f6a5198bb76968c38fa2385e0780d6768f32da304ebcd87e96c8c8024083ca575c3d97146c3daf4b1a479ac091cc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4473485dc053057ee5b97da5057d64055cc5dbe5a811dce3da13842ad8274fab4d14a5371f4619cc42ec77b0b9c3a99b8d6c4f100879e8f8a77c67a58b1fc194", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9f3b47375331a65361e31e2d300364ce52b5314811221b626425772191cc54a5782ab387a9f558e99d8eb4d91995358aee1b3acfd3bd005f4f835f264b0944ff"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 00:07:20.132235) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 00:07:20.132623) ========= 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.SCeDlpK3Z0/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.hug78Uxt7s/Listeners TMPDIR=/var/folders/_s/hn69_n6x2qs1n90vl425w26w00000w/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.005144 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 00:07:20.181336) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 00:07:20.181694) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 00:07:20.182062) ========= ========= Total master_lag: 0.22 =========