builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0230 starttime: 1464251350.45 results: success (0) buildid: 20160525234651 builduid: b88c7867d6be42868baf8782e10dff1d revision: 53942df4f3849739a4ba389656a283c3c48e01b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:10.454295) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:10.454742) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:10.455016) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:10.620804) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:10.621146) ========= 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.uVoi3Wk49R/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.iEihiLxCFs/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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-05-26 01:29:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 207M=0s 2016-05-26 01:29:11 (207 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.458134 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:11.118268) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:11.118612) ========= 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.uVoi3Wk49R/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.iEihiLxCFs/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.025167 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:11.167438) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 01:29:11.167788) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 53942df4f3849739a4ba389656a283c3c48e01b9 --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 53942df4f3849739a4ba389656a283c3c48e01b9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uVoi3Wk49R/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.iEihiLxCFs/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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-05-26 01:29:11,248 truncating revision to first 12 chars 2016-05-26 01:29:11,248 Setting DEBUG logging. 2016-05-26 01:29:11,248 attempt 1/10 2016-05-26 01:29:11,248 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/53942df4f384?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 01:29:12,263 unpacking tar archive at: fx-team-53942df4f384/testing/mozharness/ program finished with exit code 0 elapsedTime=1.281251 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 01:29:12.468439) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:12.468802) ========= script_repo_revision: 53942df4f3849739a4ba389656a283c3c48e01b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:12.469239) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:12.469519) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 01:29:12.485958) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-26 01:29:12.486299) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.uVoi3Wk49R/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.iEihiLxCFs/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:29:12 INFO - MultiFileLogger online at 20160526 01:29:12 in /builds/slave/test 01:29:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:29:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:29:12 INFO - {'append_to_log': False, 01:29:12 INFO - 'base_work_dir': '/builds/slave/test', 01:29:12 INFO - 'blob_upload_branch': 'Fx-Team', 01:29:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:29:12 INFO - 'branch': 'Fx-Team', 01:29:12 INFO - 'buildbot_json_path': 'buildprops.json', 01:29:12 INFO - 'config_files': ('talos/mac_config.py',), 01:29:12 INFO - 'default_actions': ('clobber', 01:29:12 INFO - 'read-buildbot-config', 01:29:12 INFO - 'download-and-extract', 01:29:12 INFO - 'populate-webroot', 01:29:12 INFO - 'create-virtualenv', 01:29:12 INFO - 'install', 01:29:12 INFO - 'run-tests'), 01:29:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:29:12 INFO - 'download_minidump_stackwalk': True, 01:29:12 INFO - 'download_symbols': 'ondemand', 01:29:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:29:12 INFO - 'tooltool.py': '/tools/tooltool.py', 01:29:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:29:12 INFO - '/tools/misc-python/virtualenv.py')}, 01:29:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:29:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:29:12 INFO - 'installer_path': 'installer.exe', 01:29:12 INFO - 'log_level': 'info', 01:29:12 INFO - 'log_name': 'talos', 01:29:12 INFO - 'log_to_console': True, 01:29:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:29:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:29:12 INFO - 'opt_config_files': (), 01:29:12 INFO - 'pip_index': False, 01:29:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:29:12 INFO - 'cmd': ('bash', 01:29:12 INFO - '-c', 01:29:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:29:12 INFO - 'enabled': True, 01:29:12 INFO - 'halt_on_failure': False, 01:29:12 INFO - 'name': 'check_screen_resolution'},), 01:29:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:29:12 INFO - 'cmd': ('bash', 01:29:12 INFO - '-c', 01:29:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:29:12 INFO - 'enabled': True, 01:29:12 INFO - 'halt_on_failure': False, 01:29:12 INFO - 'name': 'check_screen_resolution'},), 01:29:12 INFO - 'run_cmd_checks_enabled': True, 01:29:12 INFO - 'sps_profile': False, 01:29:12 INFO - 'sps_profile_interval': 0, 01:29:12 INFO - 'suite': 'dromaeojs', 01:29:12 INFO - 'system_bits': '32', 01:29:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:29:12 INFO - 'title': 't-yosemite-r7-0230', 01:29:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:29:12 INFO - 'use_talos_json': True, 01:29:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:29:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:29:12 INFO - 'work_dir': 'build'} 01:29:12 INFO - ##### 01:29:12 INFO - ##### Running clobber step. 01:29:12 INFO - ##### 01:29:12 INFO - Running pre-action listener: _resource_record_pre_action 01:29:12 INFO - Running main action method: clobber 01:29:12 INFO - rmtree: /builds/slave/test/build 01:29:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:29:14 INFO - Running post-action listener: _resource_record_post_action 01:29:14 INFO - ##### 01:29:14 INFO - ##### Finished clobber step (success) 01:29:14 INFO - ##### 01:29:14 INFO - ##### 01:29:14 INFO - ##### Running read-buildbot-config step. 01:29:14 INFO - ##### 01:29:14 INFO - Running pre-action listener: _resource_record_pre_action 01:29:14 INFO - Running main action method: read_buildbot_config 01:29:14 INFO - Using buildbot properties: 01:29:14 INFO - { 01:29:14 INFO - "project": "", 01:29:14 INFO - "product": "firefox", 01:29:14 INFO - "script_repo_revision": "production", 01:29:14 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:29:14 INFO - "repository": "", 01:29:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:29:14 INFO - "buildid": "20160525234651", 01:29:14 INFO - "pgo_build": "False", 01:29:14 INFO - "basedir": "/builds/slave/test", 01:29:14 INFO - "buildnumber": 791, 01:29:14 INFO - "slavename": "t-yosemite-r7-0230", 01:29:14 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 01:29:14 INFO - "platform": "yosemite_r7", 01:29:14 INFO - "scheduler": "tests-fx-team-macosx64-talos", 01:29:14 INFO - "branch": "fx-team", 01:29:14 INFO - "repo_path": "integration/fx-team", 01:29:14 INFO - "revision": "53942df4f3849739a4ba389656a283c3c48e01b9", 01:29:14 INFO - "stage_platform": "macosx64", 01:29:14 INFO - "builduid": "b88c7867d6be42868baf8782e10dff1d", 01:29:14 INFO - "slavebuilddir": "test" 01:29:14 INFO - } 01:29:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 01:29:14 INFO - Running post-action listener: _resource_record_post_action 01:29:14 INFO - ##### 01:29:14 INFO - ##### Finished read-buildbot-config step (success) 01:29:14 INFO - ##### 01:29:14 INFO - ##### 01:29:14 INFO - ##### Running download-and-extract step. 01:29:14 INFO - ##### 01:29:14 INFO - Running pre-action listener: _resource_record_pre_action 01:29:14 INFO - Running main action method: download_and_extract 01:29:14 INFO - mkdir: /builds/slave/test/build/tests 01:29:14 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:14 INFO - https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:29:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:29:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 01:29:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:29:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 01:29:17 INFO - Downloaded 1199 bytes. 01:29:17 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 01:29:17 INFO - Using the following test package requirements: 01:29:17 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:29:17 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 01:29:17 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 01:29:17 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'jsshell-mac.zip'], 01:29:17 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 01:29:17 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 01:29:17 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 01:29:17 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 01:29:17 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 01:29:17 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 01:29:17 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 01:29:17 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:29:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:17 INFO - https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:29:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:29:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:29:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:29:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:29:21 INFO - Downloaded 18622338 bytes. 01:29:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:29:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:29:24 INFO - Return code: 0 01:29:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:24 INFO - https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:29:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:29:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:29:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 01:29:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:29:25 INFO - Downloaded 18622338 bytes. 01:29:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:29:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:29:28 INFO - Return code: 0 01:29: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:28 INFO - https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:29:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:29:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:29:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 01:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:29:30 INFO - Downloaded 10834034 bytes. 01:29:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:29:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:29:30 INFO - Return code: 0 01:29:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:30 INFO - https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:29:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:29:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:29:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 01:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 01:29:33 INFO - Downloaded 110502887 bytes. 01:29:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:29:33 INFO - mkdir: /builds/slave/test/properties 01:29:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:29:33 INFO - Writing to file /builds/slave/test/properties/build_url 01:29:33 INFO - Contents: 01:29:33 INFO - build_url:https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 01:29:34 INFO - Running post-action listener: _resource_record_post_action 01:29:34 INFO - Running post-action listener: set_extra_try_arguments 01:29:34 INFO - ##### 01:29:34 INFO - ##### Finished download-and-extract step (success) 01:29:34 INFO - ##### 01:29:34 INFO - ##### 01:29:34 INFO - ##### Running populate-webroot step. 01:29:34 INFO - ##### 01:29:34 INFO - Running pre-action listener: _resource_record_pre_action 01:29:34 INFO - Running main action method: populate_webroot 01:29:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:29:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:29:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:29:34 INFO - '--tppagecycles', 01:29:34 INFO - '7'], 01:29:34 INFO - 'tests': ['tsvgm']}}, 01:29:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:29:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:29:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:29:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:29:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:29:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:29:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['damp', 'tps']}, 01:29:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'tests': ['damp', 'tps']}, 01:29:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['dromaeo_dom']}, 01:29:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:29:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other-e10s': {'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['a11yr', 01:29:34 INFO - 'ts_paint', 01:29:34 INFO - 'tpaint', 01:29:34 INFO - 'sessionrestore', 01:29:34 INFO - 'sessionrestore_no_auto_restore', 01:29:34 INFO - 'tabpaint']}, 01:29:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tsvgx', 01:29:34 INFO - 'tsvgr_opacity', 01:29:34 INFO - 'tart', 01:29:34 INFO - 'tscrollx', 01:29:34 INFO - 'cart']}, 01:29:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:29:34 INFO - 'tsvgr_opacity', 01:29:34 INFO - 'tart', 01:29:34 INFO - 'tscrollx', 01:29:34 INFO - 'cart']}, 01:29:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s'], 01:29:34 INFO - 'tests': ['tp5o']}, 01:29:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'tests': ['tp5o']}, 01:29:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'talos_options': ['--disable-e10s', 01:29:34 INFO - '--xperf_path', 01:29:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:29:34 INFO - 'tests': ['tp5n']}, 01:29:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:29:34 INFO - 'talos_options': ['--xperf_path', 01:29:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:29:34 INFO - 'tests': ['tp5n']}}, 01:29:34 INFO - 'talos.zip': {'path': '', 01:29:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:29:34 INFO - Running post-action listener: _resource_record_post_action 01:29:34 INFO - ##### 01:29:34 INFO - ##### Finished populate-webroot step (success) 01:29:34 INFO - ##### 01:29:34 INFO - ##### 01:29:34 INFO - ##### Running create-virtualenv step. 01:29:34 INFO - ##### 01:29:34 INFO - Running pre-action listener: _resource_record_pre_action 01:29:34 INFO - Running main action method: create_virtualenv 01:29:34 INFO - Creating virtualenv /builds/slave/test/build/venv 01:29:34 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 01:29:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:29:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:29:34 INFO - Using real prefix '/tools/python27' 01:29:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:29:35 INFO - Installing distribute.............................................................................................................................................................................................done. 01:29:38 INFO - Installing pip.................done. 01:29:38 INFO - Return code: 0 01:29:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:29:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:38 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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:29:38 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 01:29:38 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 01:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:38 INFO - 'HOME': '/Users/cltbld', 01:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:38 INFO - 'LOGNAME': 'cltbld', 01:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:38 INFO - 'MOZ_NO_REMOTE': '1', 01:29:38 INFO - 'NO_EM_RESTART': '1', 01:29:38 INFO - 'PAGER': '/bin/cat', 01:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:38 INFO - 'PWD': '/builds/slave/test', 01:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:38 INFO - 'SHELL': '/bin/bash', 01:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:29:38 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:38 INFO - 'USER': 'cltbld', 01:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:38 INFO - 'XPC_FLAGS': '0x0', 01:29:38 INFO - 'XPC_SERVICE_NAME': '0', 01:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:38 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 01:29:38 INFO - Cleaning up... 01:29:38 INFO - Return code: 0 01:29:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:29:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:38 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:38 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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:29:38 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 01:29:38 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 01:29:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:29:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:38 INFO - 'HOME': '/Users/cltbld', 01:29:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:38 INFO - 'LOGNAME': 'cltbld', 01:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:38 INFO - 'MOZ_NO_REMOTE': '1', 01:29:38 INFO - 'NO_EM_RESTART': '1', 01:29:38 INFO - 'PAGER': '/bin/cat', 01:29:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:38 INFO - 'PWD': '/builds/slave/test', 01:29:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:38 INFO - 'SHELL': '/bin/bash', 01:29:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:29:38 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:29:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:38 INFO - 'USER': 'cltbld', 01:29:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:38 INFO - 'XPC_FLAGS': '0x0', 01:29:38 INFO - 'XPC_SERVICE_NAME': '0', 01:29:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:38 INFO - Downloading/unpacking psutil>=3.1.1 01:29: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 01:29: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 01:29: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 01:29: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 01:29: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 01:29: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 01:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:29:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:29:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:29:41 INFO - Installing collected packages: psutil 01:29:41 INFO - Running setup.py install for psutil 01:29:42 INFO - building 'psutil._psutil_osx' extension 01:29: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 01:29: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 01:29: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 01:29: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 01:29:42 INFO - building 'psutil._psutil_posix' extension 01:29: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 01:29:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:29:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:29:42 INFO - ^ 01:29:42 INFO - 1 warning generated. 01:29: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 01:29:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:29:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:29:43 INFO - Successfully installed psutil 01:29:43 INFO - Cleaning up... 01:29:43 INFO - Return code: 0 01:29:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:29: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29: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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:29: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 01:29: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 01:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:43 INFO - 'HOME': '/Users/cltbld', 01:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:43 INFO - 'LOGNAME': 'cltbld', 01:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:43 INFO - 'MOZ_NO_REMOTE': '1', 01:29:43 INFO - 'NO_EM_RESTART': '1', 01:29:43 INFO - 'PAGER': '/bin/cat', 01:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:43 INFO - 'PWD': '/builds/slave/test', 01:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:43 INFO - 'SHELL': '/bin/bash', 01:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:29:43 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:43 INFO - 'USER': 'cltbld', 01:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:43 INFO - 'XPC_FLAGS': '0x0', 01:29:43 INFO - 'XPC_SERVICE_NAME': '0', 01:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:29: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 01:29: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 01:29: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 01:29: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 01:29: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 01:29: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 01:29:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:29:45 INFO - Installing collected packages: mozsystemmonitor 01:29:45 INFO - Running setup.py install for mozsystemmonitor 01:29:46 INFO - Successfully installed mozsystemmonitor 01:29:46 INFO - Cleaning up... 01:29:46 INFO - Return code: 0 01:29:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:29: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29: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', '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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:29: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:29: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 blobuploader==1.2.4 01:29:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:29:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:46 INFO - 'HOME': '/Users/cltbld', 01:29:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:46 INFO - 'LOGNAME': 'cltbld', 01:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:46 INFO - 'MOZ_NO_REMOTE': '1', 01:29:46 INFO - 'NO_EM_RESTART': '1', 01:29:46 INFO - 'PAGER': '/bin/cat', 01:29:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:46 INFO - 'PWD': '/builds/slave/test', 01:29:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:46 INFO - 'SHELL': '/bin/bash', 01:29:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:29:46 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:29:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:46 INFO - 'USER': 'cltbld', 01:29:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:46 INFO - 'XPC_FLAGS': '0x0', 01:29:46 INFO - 'XPC_SERVICE_NAME': '0', 01:29:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:46 INFO - Downloading/unpacking blobuploader==1.2.4 01:29: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 01:29: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 01:29: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 01:29: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 01:29: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 01:29: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 01:29:48 INFO - Downloading blobuploader-1.2.4.tar.gz 01:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:29:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:29:49 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 01:29:49 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 01:29:49 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 01:29:49 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 01:29:49 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 01:29:49 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 01:29:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:29:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:29:49 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 01:29:49 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 01:29:49 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 01:29:49 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 01:29:49 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 01:29:49 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 01:29:50 INFO - Downloading docopt-0.6.1.tar.gz 01:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:29:50 INFO - Installing collected packages: blobuploader, requests, docopt 01:29:50 INFO - Running setup.py install for blobuploader 01:29:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:29:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:29:50 INFO - Running setup.py install for requests 01:29:50 INFO - Running setup.py install for docopt 01:29:50 INFO - Successfully installed blobuploader requests docopt 01:29:50 INFO - Cleaning up... 01:29:51 INFO - Return code: 0 01:29:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:29:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:51 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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:29:51 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 01:29:51 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 01:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:51 INFO - 'HOME': '/Users/cltbld', 01:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:51 INFO - 'LOGNAME': 'cltbld', 01:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:51 INFO - 'MOZ_NO_REMOTE': '1', 01:29:51 INFO - 'NO_EM_RESTART': '1', 01:29:51 INFO - 'PAGER': '/bin/cat', 01:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:51 INFO - 'PWD': '/builds/slave/test', 01:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:51 INFO - 'SHELL': '/bin/bash', 01:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:29:51 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:51 INFO - 'USER': 'cltbld', 01:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:51 INFO - 'XPC_FLAGS': '0x0', 01:29:51 INFO - 'XPC_SERVICE_NAME': '0', 01:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:29:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-oAikow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:29:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-oj6254-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:29:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-7_zs1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:29:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-xgSXc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:29:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-V4BdS0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:29:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-O0U73r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:29:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:29:51 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-J28zQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-esATR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-k6_qcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-DCpkVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Lbq4t8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-fKwS7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-rBnvf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-uStsVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Qz3fyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:29:52 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-0Yokdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:29:53 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-eDjjdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:29:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:29:53 INFO - Running setup.py install for manifestparser 01:29:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Running setup.py install for mozcrash 01:29:53 INFO - Running setup.py install for mozdebug 01:29:53 INFO - Running setup.py install for mozdevice 01:29:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:29:53 INFO - Running setup.py install for mozfile 01:29:53 INFO - Running setup.py install for mozhttpd 01:29:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for mozinfo 01:29:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for mozInstall 01:29:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for mozleak 01:29:54 INFO - Running setup.py install for mozlog 01:29:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for moznetwork 01:29:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:29:54 INFO - Running setup.py install for mozprocess 01:29:54 INFO - Running setup.py install for mozprofile 01:29:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:29:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:29:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:29:55 INFO - Running setup.py install for mozrunner 01:29:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:29:55 INFO - Running setup.py install for mozscreenshot 01:29:55 INFO - Running setup.py install for moztest 01:29:55 INFO - Running setup.py install for mozversion 01:29:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:29:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:29:55 INFO - Cleaning up... 01:29:55 INFO - Return code: 0 01:29:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:29:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:29:55 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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:29:55 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 01:29:55 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 01:29:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:29:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:29:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:29:55 INFO - 'HOME': '/Users/cltbld', 01:29:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:29:55 INFO - 'LOGNAME': 'cltbld', 01:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:29:55 INFO - 'MOZ_NO_REMOTE': '1', 01:29:55 INFO - 'NO_EM_RESTART': '1', 01:29:55 INFO - 'PAGER': '/bin/cat', 01:29:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:29:55 INFO - 'PWD': '/builds/slave/test', 01:29:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:29:55 INFO - 'SHELL': '/bin/bash', 01:29:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:29:55 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:29:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:29:55 INFO - 'USER': 'cltbld', 01:29:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:29:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:29:55 INFO - 'XPC_FLAGS': '0x0', 01:29:55 INFO - 'XPC_SERVICE_NAME': '0', 01:29:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:29:55 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-kHQPOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-HoDVz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-5Q0pzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-rSUWJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-lRQ68m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-4jhB7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-MCDM7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-jLjWKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-HzWWVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:29:56 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)) 01:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:29:56 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-VOX58U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:29:57 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-6WkRIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:29:57 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-1O_Xp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:29:57 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-VFhiO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:29:57 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-spxKuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:57 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-wFPEYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:29:57 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-HZrkEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:29:57 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)) 01:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:29:57 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-qtbNv8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 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)) 01:29:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:29:57 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 01:29:57 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 01:29:57 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 01:29:57 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 01:29:57 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 01:29:57 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 01:30:00 INFO - Downloading blessings-1.6.tar.gz 01:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:30:00 INFO - Installing collected packages: blessings 01:30:00 INFO - Running setup.py install for blessings 01:30:00 INFO - Successfully installed blessings 01:30:00 INFO - Cleaning up... 01:30:00 INFO - Return code: 0 01:30:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:30:00 INFO - Reading from file tmpfile_stdout 01:30:00 INFO - Current package versions: 01:30:00 INFO - blessings == 1.6 01:30:00 INFO - blobuploader == 1.2.4 01:30:00 INFO - docopt == 0.6.1 01:30:00 INFO - manifestparser == 1.1 01:30:00 INFO - mozInstall == 1.12 01:30:00 INFO - mozcrash == 0.17 01:30:00 INFO - mozdebug == 0.1 01:30:00 INFO - mozdevice == 0.48 01:30:00 INFO - mozfile == 1.2 01:30:00 INFO - mozhttpd == 0.7 01:30:00 INFO - mozinfo == 0.9 01:30:00 INFO - mozleak == 0.1 01:30:00 INFO - mozlog == 3.2 01:30:00 INFO - moznetwork == 0.27 01:30:00 INFO - mozprocess == 0.22 01:30:00 INFO - mozprofile == 0.28 01:30:00 INFO - mozrunner == 6.11 01:30:00 INFO - mozscreenshot == 0.1 01:30:00 INFO - mozsystemmonitor == 0.3 01:30:00 INFO - moztest == 0.7 01:30:00 INFO - mozversion == 1.4 01:30:00 INFO - psutil == 3.1.1 01:30:00 INFO - requests == 1.2.3 01:30:00 INFO - wsgiref == 0.1.2 01:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:00 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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:30:00 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 01:30:00 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 01:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:00 INFO - 'HOME': '/Users/cltbld', 01:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:00 INFO - 'LOGNAME': 'cltbld', 01:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:00 INFO - 'MOZ_NO_REMOTE': '1', 01:30:00 INFO - 'NO_EM_RESTART': '1', 01:30:00 INFO - 'PAGER': '/bin/cat', 01:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:00 INFO - 'PWD': '/builds/slave/test', 01:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:00 INFO - 'SHELL': '/bin/bash', 01:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:30:00 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:00 INFO - 'USER': 'cltbld', 01:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:00 INFO - 'XPC_FLAGS': '0x0', 01:30:00 INFO - 'XPC_SERVICE_NAME': '0', 01:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 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)) 01:30:01 INFO - Cleaning up... 01:30:01 INFO - Return code: 0 01:30:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:30:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30:01 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 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:30:01 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 01:30:01 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 01:30:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:30:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:01 INFO - 'HOME': '/Users/cltbld', 01:30:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:01 INFO - 'LOGNAME': 'cltbld', 01:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:01 INFO - 'MOZ_NO_REMOTE': '1', 01:30:01 INFO - 'NO_EM_RESTART': '1', 01:30:01 INFO - 'PAGER': '/bin/cat', 01:30:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:01 INFO - 'PWD': '/builds/slave/test', 01:30:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:01 INFO - 'SHELL': '/bin/bash', 01:30:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:30:01 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:30:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:01 INFO - 'USER': 'cltbld', 01:30:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:01 INFO - 'XPC_FLAGS': '0x0', 01:30:01 INFO - 'XPC_SERVICE_NAME': '0', 01:30:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:01 INFO - Downloading/unpacking jsonschema 01:30: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 01:30: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 01:30: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 01:30: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 01:30: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 01:30: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 01:30:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:30:03 INFO - Downloading/unpacking functools32 (from jsonschema) 01:30:03 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 01:30:03 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 01:30:03 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 01:30:03 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 01:30:03 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 01:30:03 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 01:30:04 INFO - Downloading functools32-3.2.3-2.tar.gz 01:30:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:30:04 INFO - warning: no files found matching '*.txt' 01:30:04 INFO - no previously-included directories found matching 'build' 01:30:04 INFO - no previously-included directories found matching 'dist' 01:30:04 INFO - no previously-included directories found matching '.git*' 01:30:04 INFO - Installing collected packages: jsonschema, functools32 01:30:04 INFO - Running setup.py install for functools32 01:30:04 INFO - warning: no files found matching '*.txt' 01:30:04 INFO - no previously-included directories found matching 'build' 01:30:04 INFO - no previously-included directories found matching 'dist' 01:30:04 INFO - no previously-included directories found matching '.git*' 01:30:04 INFO - Successfully installed jsonschema functools32 01:30:04 INFO - Cleaning up... 01:30:04 INFO - Return code: 0 01:30:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 01:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:30: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d14ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d151570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe680595180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, '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.uVoi3Wk49R/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.iEihiLxCFs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 01:30: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', 'flake8'] in /builds/slave/test/build 01:30: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 flake8 01:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:04 INFO - 'HOME': '/Users/cltbld', 01:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:04 INFO - 'LOGNAME': 'cltbld', 01:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:04 INFO - 'MOZ_NO_REMOTE': '1', 01:30:04 INFO - 'NO_EM_RESTART': '1', 01:30:04 INFO - 'PAGER': '/bin/cat', 01:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:04 INFO - 'PWD': '/builds/slave/test', 01:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:04 INFO - 'SHELL': '/bin/bash', 01:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:30:04 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:04 INFO - 'USER': 'cltbld', 01:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:04 INFO - 'XPC_FLAGS': '0x0', 01:30:04 INFO - 'XPC_SERVICE_NAME': '0', 01:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:30:05 INFO - Downloading/unpacking flake8 01:30:05 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 01:30:05 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 01:30:05 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 01:30:05 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 01:30:05 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 01:30:05 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 01:30:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 01:30:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 01:30:07 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 01:30:07 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 01:30:07 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 01:30:07 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 01:30:07 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 01:30:07 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 01:30:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 01:30:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 01:30:07 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 01:30:07 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 01:30:07 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 01:30:07 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 01:30:07 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 01:30:07 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 01:30:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 01:30:08 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 01:30:08 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 01:30:08 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 01:30:08 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 01:30:08 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 01:30:08 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 01:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 01:30:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:30:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:30:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:30:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:30:08 INFO - no previously-included directories found matching 'docs/_build' 01:30:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 01:30:08 INFO - Running setup.py install for pep8 01:30:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 01:30:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 01:30:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 01:30:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 01:30:09 INFO - no previously-included directories found matching 'docs/_build' 01:30:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 01:30:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 01:30:09 INFO - Cleaning up... 01:30:09 INFO - Return code: 0 01:30:09 INFO - Running post-action listener: _resource_record_post_action 01:30:09 INFO - Running post-action listener: _start_resource_monitoring 01:30:09 INFO - Starting resource monitoring. 01:30:09 INFO - ##### 01:30:09 INFO - ##### Finished create-virtualenv step (success) 01:30:09 INFO - ##### 01:30:09 INFO - ##### 01:30:09 INFO - ##### Running install step. 01:30:09 INFO - ##### 01:30:09 INFO - Running pre-action listener: _resource_record_pre_action 01:30:09 INFO - Running main action method: install 01:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:30:09 INFO - Reading from file tmpfile_stdout 01:30:09 INFO - Detecting whether we're running mozinstall >=1.0... 01:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:30:09 INFO - Reading from file tmpfile_stdout 01:30:09 INFO - Output received: 01:30:09 INFO - Usage: mozinstall [options] installer 01:30:09 INFO - Options: 01:30:09 INFO - -h, --help show this help message and exit 01:30:09 INFO - -d DEST, --destination=DEST 01:30:09 INFO - Directory to install application into. [default: 01:30:09 INFO - "/builds/slave/test"] 01:30:09 INFO - --app=APP Application being installed. [default: firefox] 01:30:09 INFO - mkdir: /builds/slave/test/build/application 01:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:30:43 INFO - Reading from file tmpfile_stdout 01:30:43 INFO - Output received: 01:30:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:30:43 INFO - Running post-action listener: _resource_record_post_action 01:30:43 INFO - ##### 01:30:43 INFO - ##### Finished install step (success) 01:30:43 INFO - ##### 01:30:43 INFO - ##### 01:30:43 INFO - ##### Running run-tests step. 01:30:43 INFO - ##### 01:30:43 INFO - Running pre-action listener: _resource_record_pre_action 01:30:43 INFO - Running main action method: run_tests 01:30:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:30:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:30:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:30:43 INFO - 2016-05-26 01:30:43.047 screenresolution[1646:8205] starting screenresolution argv=screenresolution get 01:30:43 INFO - 2016-05-26 01:30:43.049 screenresolution[1646:8205] Display 0: 1600x1200x32@60 01:30:43 INFO - 2016-05-26 01:30:43.055 screenresolution[1647:8207] starting screenresolution argv=screenresolution list 01:30:43 INFO - Available Modes on Display 0 01:30:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:30:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:30:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:30:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:30:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:30:43 INFO - Intel Iris: 01:30:43 INFO - Chipset Model: Intel Iris 01:30:43 INFO - Type: GPU 01:30:43 INFO - Bus: Built-In 01:30:43 INFO - VRAM (Dynamic, Max): 1536 MB 01:30:43 INFO - Vendor: Intel (0x8086) 01:30:43 INFO - Device ID: 0x0a2e 01:30:43 INFO - Revision ID: 0x0009 01:30:43 INFO - Displays: 01:30:43 INFO - Display: 01:30:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:30:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:30:43 INFO - Display Serial Number: 1600x1200 60 01:30:43 INFO - Main Display: Yes 01:30:43 INFO - Mirror: Off 01:30:43 INFO - Online: Yes 01:30:43 INFO - Rotation: Supported 01:30:43 INFO - Return code: 0 01:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:30:43 INFO - Python 2.7.3 01:30:43 INFO - Return code: 0 01:30:43 INFO - grabbing minidump binary from tooltool 01:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:30:43 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 0x10cfefd30>, '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 0x10ce13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cfe6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:30:43 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 01:30:43 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 01:30:43 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 01:30:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:30:43 INFO - Return code: 0 01:30:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:30:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:30:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:30:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:30:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 01:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 01:30:44 INFO - Return code: 0 01:30:44 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:30:44 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0230 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uVoi3Wk49R/Render', 01:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:30:44 INFO - 'HOME': '/Users/cltbld', 01:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:30:44 INFO - 'LOGNAME': 'cltbld', 01:30:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:30:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:30:44 INFO - 'MOZ_NO_REMOTE': '1', 01:30:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:30:44 INFO - 'NO_EM_RESTART': '1', 01:30:44 INFO - 'PAGER': '/bin/cat', 01:30:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:30:44 INFO - 'PWD': '/builds/slave/test', 01:30:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:30:44 INFO - 'SHELL': '/bin/bash', 01:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iEihiLxCFs/Listeners', 01:30:44 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 01:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:30:44 INFO - 'USER': 'cltbld', 01:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:30:44 INFO - 'XPC_FLAGS': '0x0', 01:30:44 INFO - 'XPC_SERVICE_NAME': '0', 01:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:30:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:30:44 INFO - mozversion application_buildid: 20160525234651 01:30:44 INFO - mozversion application_changeset: 53942df4f3849739a4ba389656a283c3c48e01b9 01:30:44 INFO - mozversion application_display_name: Nightly 01:30:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:30:44 INFO - mozversion application_name: Firefox 01:30:44 INFO - mozversion application_remotingname: firefox 01:30:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:30:44 INFO - mozversion application_vendor: Mozilla 01:30:44 INFO - mozversion application_version: 49.0a1 01:30:44 INFO - mozversion platform_buildid: 20160525234651 01:30:44 INFO - mozversion platform_changeset: 53942df4f3849739a4ba389656a283c3c48e01b9 01:30:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:30:44 INFO - mozversion platform_version: 49.0a1 01:30:44 INFO - using testdate: 1464251444 01:30:44 INFO - actual date: 1464251444 01:30:44 INFO - starting webserver on 'localhost:49275' 01:30:44 INFO - SUITE-START | Running 2 tests 01:30:44 INFO - TEST-START | dromaeo_css 01:30:44 INFO - operating with platform_type : mac_ 01:30:44 INFO - Initialising browser for dromaeo_css test... 01:30:44 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpiB6vIj/profile http://localhost:49275/getInfo.html) 01:30:46 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 01:30:46 INFO - PROCESS | 1665 | colorDepth:24 01:30:46 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 01:30:46 INFO - PROCESS | 1665 | __metrics 01:30:47 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:30:47 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:30:47 INFO - TEST-INFO | 1665: exit 0 01:30:47 INFO - Browser initialized. 01:30:47 INFO - Running cycle 1/1 for dromaeo_css test... 01:30:47 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpiB6vIj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:30:59 INFO - PROCESS | 1668 | RSS: Main: 241401856 01:30:59 INFO - PROCESS | 1668 | 01:31:51 INFO - PROCESS | 1668 | 1464251511161 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] 01:34:02 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 01:34:03 INFO - PROCESS | 1668 | RSS: Main: 374484992 01:34:03 INFO - PROCESS | 1668 | 01:37:06 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 01:37:07 INFO - PROCESS | 1668 | RSS: Main: 378429440 01:37:07 INFO - PROCESS | 1668 | 01:40:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 01:40:09 INFO - PROCESS | 1668 | RSS: Main: 375410688 01:40:09 INFO - PROCESS | 1668 | 01:43:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 01:43:12 INFO - PROCESS | 1668 | RSS: Main: 372367360 01:43:12 INFO - PROCESS | 1668 | 01:46:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 01:46:16 INFO - PROCESS | 1668 | RSS: Main: 345137152 01:46:16 INFO - PROCESS | 1668 | 01:49:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 01:49:20 INFO - PROCESS | 1668 | RSS: Main: 380002304 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | __start_tp_report 01:49:20 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 01:49:20 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 01:49:20 INFO - PROCESS | 1668 | |i|pagename|runs| 01:49:20 INFO - PROCESS | 1668 | |0;dojo.html;3431.8455669494874;3469.8091604961733;3482.181687303484;3505.421605435103;3508.245727168658;5618.016847051765;5623.915641265385;5654.236678048463;5724.656520608761;5739.167820666006;5664.830055098343;5710.000749868777;5782.595218334708;5785.5082318002915;5812.738426770795;5101.464346243647;5128.333316668846;5200.08998425276;5219.399769026572;5276.076686579833;5247.7900883964785;5291.708956007418;5302.761375738101;5319.7340132993195;5339.332583427072;5102.36220472441;5111.4121875984365;5120.308758317617;5130.897382052338;5216.921746173792;5148.047611191904;5154.098032844237;5160.380754309469;5181.4559471255725;5187.974060129675;5144.279800827887;5181.974090129563;5185.351831021122;5190.455002224788;5268.394134674523;5512.448755124496;5586.944130558683;5633.8591535211535;5649.576281778883;5678.914816277759;6223.968880155616;6264.060390941349;6264.310925798159;6270.905936410957;6273.466755325757;6256.343083976162;6305.243370795534;6319.842003949892;6327.303996560375;6334.904976425358;5662;5751.137329400582;5774.018416869142;5780.508656764138;5820.796272130455;6278;6289.433950944345;6299.559030867796;6309.968450157811;6312.779052733133;6118.847028824226;6132.702084519407;6138.772245550907;6149.7847575335545;6167.444929956326;6084.4828189603495;6199;6245.875082498324;6255;6316.4315211630255;6164.383561643889;6204.286507051746;6216.4405203531;6230.688465576703;6234.750609975641;5917.112433134978;6180.443760061616;6232.532560058014;6273.435390814849;6282;4762.618990480753;4800.35195248637;4819.734914579732;4877.7073375596865;4913.533214344631;4376.1685279797375;4392.1874453226255;4401.581849724271;4426.756528390905;4461;5012.448630650625;5749.482546570745;5768.307803063575;5778.682172480554;5830.300363956352;5492.736670565792;5889.38161493037;6157.538184636171;6168.691565421711;6308.337624549448;4754.358161648133;5714.942850571441;5715.057015592381;5725.08398656213;5730.971345143331;5284.603654725834;5371.75827087782;5375.354957405137;5375.9462405376225;5380.596455265871;6196.10156527301;6254.655993920378;6266.060090986389;6279.403456671609;6302.338254483213;6302.71637776292;6309.652969086745;6314.400131987454;6333.113364129118;6333.524985626004;6300.684965751786;6317.336679648754;6324.525660575568;6327.90508142369;6333.8733225336155;6047.48596369296;6109.144719739151;6112.929053818819;6124.533510047433;6131.84670383244;6264.906026409516;6291.811245662638;6308.716107775253;6309.652969086561;6313.526485513513;6043.063325184604;6063.545234107548;6072.544559157993;6084.300305464928;6085.421884920926;5715.371309156073;5927.9703601481715;5990.891685038282;6032.426919442646;6063;5930.169776231417;5941.1088336749835;5953.493953014043;5991.940080599138;6018.458338749533;5081.364829396325;6079.787207447718;6081.695915204134;6137.539684523768;6142.9078563820685;5754.050581654147;5821.301443826768;5826.94173058264;5879.412058794086;5897.115432685132 01:49:20 INFO - PROCESS | 1668 | |1;ext.html;518.8339731285988;523.8716514453957;532.2893936594646;551.7778121460963;565.0535353283251;30165.94419195326;30887.764489420424;40782;41345.17309653805;42276.57723422773;40618.390724139164;41306.38040429396;41470.58529414705;43181.78409107958;43494.13011739763;9020.54897255138;9078.909210907905;9762.26782991275;9767;9787.755306117368;8651.870221946676;11613.941930290379;11804.999999999956;11806.291622502677;12116.454759535798;11769.823452648174;11880.40597970098;11899.7620047599;11901.464434100442;11909.106816988717;11822.58620948267;11920.701982450422;11935.761284774257;11938.761224775542;11953.521859125669;11782.646520604396;11887.10846686502;11906.58326958561;11916.880831191664;11939.641810745692;39879;40124.59875401267;40176.59823401787;40176.995575110566;40269.59730402717;3125.5780469636534;3174.5873036505336;3180.3162320101287;3269.1500209945348;3364.394409006378;24535.63196552053;28457.14628561126;28537.429251414855;29499.70500294991;29536.26159346012;2698.0407796998934;2714.443539074485;2731.235254128847;2738.698743138253;2936.0164344944255;505422;507527;507656;507748;507748;56240;56994.43005569974;57004;57157.714211428676;57194.71402643043;63056.68471657704;63145.36854631395;63160.684196578724;63228.683856581345;63342.36657633457;15853.762193567105;16277.918610406872;16283.674326513403;16300.347986080424;16304.429344972868;11484.196106272648;11748.236364636272;12710.555130570385;12754.61736147917;12785.808212876815;15518.60332570074;15679.588837004725;15873.761893571605;15969.520914372355;16106.30883757212;6561.901571476432;6567.310432404529;6598.934010659832;6647.069410282565;6654.23476300222;880.6477409036437;1072.672834785398;1073.3291692692067;1075.1828610256262;1076.9946150269197;28571;28698.713012870023;28835;29290;29490.557641635394;1039.6517166749072;1057.5293994172519;1079.4494807648;1129.717570607348;1168.8831116888243;13309.93345033288;14767.483138090116;14841.109533428029;15309.234538273042;15315.387384504495;185423;188987;189126;189335;189663;59987.400125999055;60045.69977150086;60177.79644407175;60227.39772602218;60239.698801505714;1292.3925754895183;1419.4180386041467;1448.833384160814;1467.713795809807;1474.395497845878;27608.585871212348;29735.405291894473;29933.251668708457;30192.5471117929;30501.389972199988;41587.7920610395;48527.5147248523;48961.51038489569;49523.50476495333;49609;489622;492140;492259;492948;492992;31372;32991.175220619676;33773.83113084419;39051.82844514669;39698.206035879695;44927.32609010802;45160;45492.772536137105;45987.08025839531;46419.76790116028;37494;37526.62473375232;37587.62412375951;38018.61981380151;38299.23401532009;36384.636153639185;37023.81488092542;37513.437298439996;37698.81150594229;37953.81023094977;36317;36374.81812591026;36790.8160459196;37344.62655373412;37755.05612359713;24377.878110609334;31799.68200317967;32776;32779.34441311208;32980.1754956128;20200.797992020478;24012.87993560021;24681.506369872863;24917.501649966536;25170.24489265325 01:49:20 INFO - PROCESS | 1668 | |2;jquery.html;385.3583783001303;387.2835254778659;388.60168327464345;399.57245747050666;406.0073121218622;19517.512062198453;21041.789582104175;21202.46993825155;21325.93370331485;22381.88090595472;16178.514644560682;20724.171033158695;21275.829829359416;21688.457788555294;21785.782142178574;5320.361556613192;5691.886162276742;5857.443542863442;6144.7542098316235;6153.969230153843;5855.180274761516;5991.400859914017;6062.727177277011;6113.510919126482;6119.112728654342;6054.788082417116;6062.848428789293;6072.997955337386;6082.665453400061;6084.08738689198;5951.613145145595;6032.607880487776;6058.848528786772;6062.181605483247;6077.088436734481;5110.463401342842;5258.894822103574;5300.151975683911;5306.946930530684;5314.495122963351;26042.34894127643;26911.730882691118;26921;27066.45867082667;27305.180844574694;2047.8873661948587;2106.504971331751;2202.9008694608783;2241.6637504374476;2327.371609665398;9330.58012389444;14630.487932922402;14788.70422591553;15015.324310406058;15622.218889055503;1683.6969345517803;1713.8691488675947;1780.5993651428455;1799.8750780762023;1837.4946889605333;104982;118928.40535797352;127382.3630881849;128145.82614651686;128635.99999999907;11495.88504114965;11510.136739744552;11648.708782280512;11652.76694466106;11817.468213930222;14792.778108328384;14890.553283401516;14927.029743066883;14942.477013304482;14944;8475.364347673949;8605.612747426381;8621.741347759576;8741.213290803731;8741.431806932529;8542.829143417097;8573.22840944318;8579.35654825878;8693.174148455886;8706.782330441663;4506.324051392317;4597.678162528591;4612.3542704021465;4636.79134438951;4680.836170734076;1923.6633589121848;1945.4844466216464;1955.1495099631707;1959.6080783843288;1960.1865225931365;894.6600291889171;903.1781079217881;930.776613612728;951.6954574536082;959.5154447004358;20061.602256755687;20848.895755521124;20869.1652333908;20954.057067432;20960.895195523924;890.3500444675424;900.0774206438452;925.2829057480507;941.8116376724546;962.8844538655406;9209.125133112344;9337.859932101024;10716.892831071746;10851.372294155939;11040.116790656888;573876;574198;585666;597529;597851;122838;123222.76777232293;125403;125851;127612;1002.979940401188;1009.6466236817203;1034.5913364221242;1057.3285963413134;1060.3319908457622;15308.464203752816;15309.08145511273;17677.823221767616;18024.549386265448;18275.634487310446;47227;48817.755911220214;49922.500774991786;50861.49138508715;52104;58025;63767;67063.66468167628;74736.25263747294;76617.23382766101;8104.756857294291;8141.389395795458;8143.79640508992;8297.128801475757;8300.70947516834;11179.552817887194;12288.385580721108;12525.37373131358;12607.495700172258;12680.365981700694;10623.89376106229;10788.89928281866;10813.351198928089;10999.750436735712;11074.335539867634;7253.057102576631;10912.290701104403;10969.50637221343;11254.099672026054;11279.210455268052;7269.491135620456;10650.8402373966;11257.324560526395;11318.830217546578;11486.425678716198;7995.520268783892;8112.148224436585;8137.186281371815;8319.043310019426;8426.368022398223;10460;10932.344059356146;11194;11210.775784484427;11259.493322800658 01:49:20 INFO - PROCESS | 1668 | |3;mootools.html;1414.0101928649947;1556.5096994446749;1599.2563457992032;1813.3381315819722;1963.1950900130948;7708.845823083535;8342.332613390929;8570.400071994964;8572.185642363966;8662.74011779646;8412.1587841216;8628.525431101287;8637;8641.611127499262;8649.956750216255;2343.5430091132193;2377.286813955815;2379.167291447988;2381.988090059547;2384.332386931662;2338.7661233876647;2357.6581395697613;2380.702412198475;2384.415818124562;2388.916387926423;2341.4029422497324;2358.9646155307682;2368;2378.940526486834;2380.7143142822883;1983.6528607493776;2310.803581695541;2337.3572267626455;2340.1809366721595;2365.077619728307;2330.3358542815217;2331.7318508371586;2333.1134169015663;2333.754955729658;2336.719593648756;8539.615717292736;8558.828823423497;8680.30557555394;8681.088485708973;8831.955840220822;5271.841844744664;5412.783488660449;5418.972905135489;5423.2949716537;5428.37573679028;3389.5254664347012;3516.8769093081874;3520.647935206459;3540.185757275841;3590.7845529268066;919.4804935211585;980.9705708828747;982.3860087445347;1022.7749895023084;1031.8349064149775;11356.26184298018;11462.312261264351;11468.4839182237;11484.827727584094;11490.36802975836;4520.864374068718;4550.203714349976;4571.839985600488;4624.260118381043;4640.164770341371;9072.50101244438;9078.500682462529;9083.500407477652;9087.136722011399;9097.590608422637;5210.452902445264;5212.739363031833;5213.556847667915;5221.921671174935;5233.241180028875;4389.012472193731;4550.13547425988;4700.976495117571;4748.050389922029;4857.077155340474;3721.4603882436895;3745.887623371303;3796.1838204786104;3826.885193444201;3936.527616685959;1050.3960222871879;1120.176670147441;1188.0990415335566;1188.7028242939266;1205.1624121235657;811.0591713612299;863.2834747159842;902.0888902208816;904.6878826804742;949.8907625623003;5468.589855760833;5621.887562248732;5623.212750214973;5695.686737229409;5877.676727780014;426.8932766808298;442.20182570459986;452.7532494790347;454.1530046463322;460.8663487588562;3777.3956167013694;3803.6196380362294;3813.923721525554;3881.825317860703;3891.552471465761;10254.333468324536;10516.737081573021;10660.09389201911;10669;10694.786104278026;10582.629607963685;10614.893851061544;10664.520096595672;10686.946565267279;10705;6891.827704307332;7865.488743231786;8115.675372985014;8167.42828002046;8167.509949402936;5612.158176273591;5634.690092044977;5832.416758324133;5916.792912248051;5918.905002574537;9501.28740344463;9979.950100249453;9982.251331150337;9982.35114717541;10003.34978226413;9722.902770972483;10021.849672255068;10030.448325342177;10046.196304295492;10137.290389672653;6584.868302633825;6780.254172040973;6781.355771201728;7390.6674199660065;7518.060242469692;8231.42380033392;8294.917050829414;8381.078081411162;8396.664133434839;8408.117147699404;7295.124585049828;7380.96309518449;7384.224656410999;7514.511556748794;7542.132654744555;7246.6376681166785;7267.527610705287;7306.890396644161;7365.7790266292095;7407.1852096268285;6755.729770809309;6779.389854913082;6801.489888258499;6858.691358888762;6869.313068693091;4757.7621118943225;4765.189917714067;4836.105320515716;4897.510248975074;5035.420926593342;4803.663743537919;4841.370621819141;4848.515148485264;4911.042346742351;5068.670536415121 01:49:20 INFO - PROCESS | 1668 | |4;prototype.html;277.3440812478489;308.33246022361584;313.5218791343203;316.5615622363027;319.8912369794271;4943.876403089925;5289.704022514489;5344.583685323388;5365.0224125610275;5376.408595054546;4137.572537474566;5007.0486607544635;5173.801134517825;5236.083685355066;5384.1923711443305;3467.127751014449;3861.9572715366835;3865.439511270864;3976.4234186043004;4115.259253334399;3392.643772403899;3605.1708107135373;3767.566729826078;4075.1645912587846;4098.5901409858925;3866.6906647468136;3980.800959952005;4046.6358027777496;4052.49343832021;4076.388541718751;3737.6636102750613;3837.635424634669;3962.8216730247063;4013.57857424972;4039.7172197946156;3513.5959364672876;3515.418061872168;3519.9616113246652;3536.964630353715;3539.43369060949;4215;4222.535521092677;5024.246363045572;5045.772940217662;5053.216751403538;3920.490336256297;4601.378813860119;4624.930626040578;4636.258198688193;4639.86080417588;4939.900542269521;5138.7687554059785;5210.895782084375;5609.635373700697;5618.578606604521;3260.9184770380693;3273.29624130813;3472.2881809229048;3654.7441679082476;3820.4460353248624;5145.84562463123;5156.581939966539;5435.809746658848;5827.592068555203;5937.643741375531;3839.752080573769;3849.8845034648443;3854.475318666278;3877.1470276539117;3880.8447662093763;3940.2119576084897;3943.495880116147;3944.1322908959983;3947.2500224957166;3966.7421617594764;4386.451693538308;4540.545945405433;4583.977080114578;4592.724436533752;4597.034622729264;5234.67057159568;5265.05085219892;5314;5622.409646987007;5667.291588551431;1300.5253082624963;1338.4244774746758;1339.270097796703;1340.4571148684797;1347.5148946379295;788.3929374381694;807.9878801817978;823.8805373220849;828.846663367279;836.690579243482;4429.601335879722;5084.898302033938;5090.389153301628;5205.06308864408;5238.109521381374;5051.51445502602;5192.507596353771;5208.40619972218;5760.971195143998;5800.709964501758;4358.869233922987;4597.402467605792;4891.437484689306;5210.697325668583;5237.790488380422;2806.2984253936515;2842.701516340796;2860.3850172213074;2893.958175056978;3016.441958237733;4823;5145.665531740425;5234.42421333653;5597.3132896210045;5993.430624090704;4888.462269150391;5669.922714684197;5928.881422371528;5956.15827791081;6015.586337210752;5010.9248361273885;5786.987277226469;5793.536517078708;6130.149480491504;6135.938640613536;5160.948390516047;5180.8137995133475;5644.8306550803545;5853.2976042875125;5897.3807750187325;5776.277965254343;5784.021229067118;5784.305883294031;5792.884142317046;5805;5634.6337488063145;6071.632063495152;6110.602810817282;6110.7250173741395;6146.815595532141;3573.5533058367705;3679.5695857657224;3899.9275199320414;3901.7854018028147;3908.1988192421054;5276.15581506957;5531.585131115102;5812.564057695605;5833.737481813244;5872.794452194153;5055.216441451581;5591.4688104629995;5622.8313150605545;5636.718164091697;5652.406497317723;4612.746298953455;5637.182608521659;5642.266505354278;5646.407127251744;5670.262865827416;4769.379980602499;5540.224368588481;5547.722613869209;5564.471375219509;5574.247476590608;5099.209622508517;5340.706261155674;5386.061018033583;5611.494965453129;5731.828045158652;4708.693934894221;5383.631202270988;5472.808451704172;5517.668939863461;5542.2795036644975 01:49:20 INFO - PROCESS | 1668 | |5;yui.html;609.7652403824527;630.7256343490585;662.6057495790005;741.7218543046357;787.0830482487902;14589;14754.704905901875;14943.626409339771;15021.399144034252;15027.774583381244;13908.791368129487;13935.303234838268;13944.721105577908;13967.301634918238;13973.580792576213;718.0629278791175;734.5188901269677;871.7951281448854;886.5257087458227;912.7079334612927;891.4695756025154;903.7605034665819;905.8595917632778;906.9637214511412;907.9818403631923;957.3155194036262;958.1281034258826;958.5878072428853;961.1685891703661;961.4615815143542;738.2691135775597;905.7011186308569;945.5083356654501;948.5684013773716;954.0078318548701;708.9397401220903;709.1313141401793;710.4458522352573;712.0245263988369;712.3909057755596;14422.639434014129;14452.060616059922;14452.566423007327;14464.927675361661;14490.92754536231;12006.939965300118;12099.516019359215;12164.14850960433;12343.259404435763;12453.153389541018;10652.147828173727;10709.78580428387;10733.946330268376;11052.94996975286;11105.500252488657;3085.891993780207;3354.66453354665;3543.9114022149392;3930.960690393088;4283.614474697292;65216.673916630585;67745.66127169383;77029;80466.59766701129;80789.38421231601;9245.583948722324;9341.953290233505;9377;9456.952715236379;9853.408795472295;19073.427797166107;19286.035698215033;19335.613287734166;19336.80663193378;19370.128344224544;7309.049823523014;7344.669489872968;7384;7636.198199189005;7724.806879828049;3803.1633040730994;3960.029792700749;3969.273341300992;3994.4407782910416;4000.71246920058;2982.4035192961496;3587.300476407128;3619.6199399063244;3643.107438677541;3654.415293553072;652.6932341799346;654.3162395296927;655.9967200163968;658.6781594877998;667.2760055339913;477.51770711581554;519.4960887938769;538.7710223155143;563.1468325486891;585.4672248254153;6171.321154672983;7269.20038795732;7369.926300736924;7621.085469743555;7967.123616402191;498.8752811797051;523.3614989712545;542.4710906865774;567.222904620664;584.868404608968;3169.889053883103;3723.6408710820897;3774.7923864187733;3823.25446537928;4004.4593979812353;80662;81978.18021819825;84223.5788821052;85551;86022;75274;75289;75598;76105.6194719034;76709;67927.64144717078;68159;68385;68608.6569567159;68976.31023689698;7481.869820837161;7494.587797671181;7548.735794247395;7620.733274335372;7646.502977306457;36906.63093369032;41332.38001430041;43179.568204317555;44162.77918610515;44287.55712442963;60793.39206607877;60857.08714369199;60894.391056090644;60933.086003710865;60978.69510652418;9716.708498745049;9848.803023939337;9880.25898057663;9925.602975880884;10149.441780702133;10678.8398174029;11072.833907491555;11075.944620276847;11151.442427878736;11592.246503977214;9847.162991145833;10332.690019299433;10339.534720937727;10371.481425928825;10375.844362334721;8805.471671699719;10446.112080473245;10503.894961050291;10514.053735163872;10547.367157970546;9893.703188904345;10022;10267.58929642806;10320.225983051452;10326.896731032593;8561.22948934599;8573.356998225034;8738;8835.955820220857;8946.68686595966;8507;8573.828523429622;8678;8717.215450609476;8721.433106848035 01:49:20 INFO - PROCESS | 1668 | __end_tp_report 01:49:20 INFO - PROCESS | 1668 | __start_cc_report 01:49:20 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1336 01:49:20 INFO - PROCESS | 1668 | __end_cc_report 01:49:20 INFO - PROCESS | 1668 | __startTimestamp1464252560201__endTimestamp 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | ------- Summary: start ------- 01:49:20 INFO - PROCESS | 1668 | Number of tests: 6 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5716.92 Median:5889.38 stddev:638.48 (10.8%) stddev-sans-first:614.76 01:49:20 INFO - PROCESS | 1668 | Values: 3431.8 3469.8 3482.2 3505.4 3508.2 5618.0 5623.9 5654.2 5724.7 5739.2 5664.8 5710.0 5782.6 5785.5 5812.7 5101.5 5128.3 5200.1 5219.4 5276.1 5247.8 5291.7 5302.8 5319.7 5339.3 5102.4 5111.4 5120.3 5130.9 5216.9 5148.0 5154.1 5160.4 5181.5 5188.0 5144.3 5182.0 5185.4 5190.5 5268.4 5512.4 5586.9 5633.9 5649.6 5678.9 6224.0 6264.1 6264.3 6270.9 6273.5 6256.3 6305.2 6319.8 6327.3 6334.9 5662.0 5751.1 5774.0 5780.5 5820.8 6278.0 6289.4 6299.6 6310.0 6312.8 6118.8 6132.7 6138.8 6149.8 6167.4 6084.5 6199.0 6245.9 6255.0 6316.4 6164.4 6204.3 6216.4 6230.7 6234.8 5917.1 6180.4 6232.5 6273.4 6282.0 4762.6 4800.4 4819.7 4877.7 4913.5 4376.2 4392.2 4401.6 4426.8 4461.0 5012.4 5749.5 5768.3 5778.7 5830.3 5492.7 5889.4 6157.5 6168.7 6308.3 4754.4 5714.9 5715.1 5725.1 5731.0 5284.6 5371.8 5375.4 5375.9 5380.6 6196.1 6254.7 6266.1 6279.4 6302.3 6302.7 6309.7 6314.4 6333.1 6333.5 6300.7 6317.3 6324.5 6327.9 6333.9 6047.5 6109.1 6112.9 6124.5 6131.8 6264.9 6291.8 6308.7 6309.7 6313.5 6043.1 6063.5 6072.5 6084.3 6085.4 5715.4 5928.0 5990.9 6032.4 6063.0 5930.2 5941.1 5953.5 5991.9 6018.5 5081.4 6079.8 6081.7 6137.5 6142.9 5754.1 5821.3 5826.9 5879.4 5897.1 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:55592.33 Median:3224.73 stddev:112697.09 (3494.8%) stddev-sans-first:112937.37 01:49:20 INFO - PROCESS | 1668 | Values: 518.8 523.9 532.3 551.8 565.1 30165.9 30887.8 40782.0 41345.2 42276.6 40618.4 41306.4 41470.6 43181.8 43494.1 9020.5 9078.9 9762.3 9767.0 9787.8 8651.9 11613.9 11805.0 11806.3 12116.5 11769.8 11880.4 11899.8 11901.5 11909.1 11822.6 11920.7 11935.8 11938.8 11953.5 11782.6 11887.1 11906.6 11916.9 11939.6 39879.0 40124.6 40176.6 40177.0 40269.6 3125.6 3174.6 3180.3 3269.2 3364.4 24535.6 28457.1 28537.4 29499.7 29536.3 2698.0 2714.4 2731.2 2738.7 2936.0 505422.0 507527.0 507656.0 507748.0 507748.0 56240.0 56994.4 57004.0 57157.7 57194.7 63056.7 63145.4 63160.7 63228.7 63342.4 15853.8 16277.9 16283.7 16300.3 16304.4 11484.2 11748.2 12710.6 12754.6 12785.8 15518.6 15679.6 15873.8 15969.5 16106.3 6561.9 6567.3 6598.9 6647.1 6654.2 880.6 1072.7 1073.3 1075.2 1077.0 28571.0 28698.7 28835.0 29290.0 29490.6 1039.7 1057.5 1079.4 1129.7 1168.9 13309.9 14767.5 14841.1 15309.2 15315.4 185423.0 188987.0 189126.0 189335.0 189663.0 59987.4 60045.7 60177.8 60227.4 60239.7 1292.4 1419.4 1448.8 1467.7 1474.4 27608.6 29735.4 29933.3 30192.5 30501.4 41587.8 48527.5 48961.5 49523.5 49609.0 489622.0 492140.0 492259.0 492948.0 492992.0 31372.0 32991.2 33773.8 39051.8 39698.2 44927.3 45160.0 45492.8 45987.1 46419.8 37494.0 37526.6 37587.6 38018.6 38299.2 36384.6 37023.8 37513.4 37698.8 37953.8 36317.0 36374.8 36790.8 37344.6 37755.1 24377.9 31799.7 32776.0 32779.3 32980.2 20200.8 24012.9 24681.5 24917.5 25170.2 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34398.27 Median:26477.04 stddev:97800.35 (369.4%) stddev-sans-first:98041.36 01:49:20 INFO - PROCESS | 1668 | Values: 385.4 387.3 388.6 399.6 406.0 19517.5 21041.8 21202.5 21325.9 22381.9 16178.5 20724.2 21275.8 21688.5 21785.8 5320.4 5691.9 5857.4 6144.8 6154.0 5855.2 5991.4 6062.7 6113.5 6119.1 6054.8 6062.8 6073.0 6082.7 6084.1 5951.6 6032.6 6058.8 6062.2 6077.1 5110.5 5258.9 5300.2 5306.9 5314.5 26042.3 26911.7 26921.0 27066.5 27305.2 2047.9 2106.5 2202.9 2241.7 2327.4 9330.6 14630.5 14788.7 15015.3 15622.2 1683.7 1713.9 1780.6 1799.9 1837.5 104982.0 118928.4 127382.4 128145.8 128636.0 11495.9 11510.1 11648.7 11652.8 11817.5 14792.8 14890.6 14927.0 14942.5 14944.0 8475.4 8605.6 8621.7 8741.2 8741.4 8542.8 8573.2 8579.4 8693.2 8706.8 4506.3 4597.7 4612.4 4636.8 4680.8 1923.7 1945.5 1955.1 1959.6 1960.2 894.7 903.2 930.8 951.7 959.5 20061.6 20848.9 20869.2 20954.1 20960.9 890.4 900.1 925.3 941.8 962.9 9209.1 9337.9 10716.9 10851.4 11040.1 573876.0 574198.0 585666.0 597529.0 597851.0 122838.0 123222.8 125403.0 125851.0 127612.0 1003.0 1009.6 1034.6 1057.3 1060.3 15308.5 15309.1 17677.8 18024.5 18275.6 47227.0 48817.8 49922.5 50861.5 52104.0 58025.0 63767.0 67063.7 74736.3 76617.2 8104.8 8141.4 8143.8 8297.1 8300.7 11179.6 12288.4 12525.4 12607.5 12680.4 10623.9 10788.9 10813.4 10999.8 11074.3 7253.1 10912.3 10969.5 11254.1 11279.2 7269.5 10650.8 11257.3 11318.8 11486.4 7995.5 8112.1 8137.2 8319.0 8426.4 10460.0 10932.3 11194.0 11210.8 11259.5 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5584.33 Median:4844.94 stddev:3151.39 (65.0%) stddev-sans-first:3144.65 01:49:20 INFO - PROCESS | 1668 | Values: 1414.0 1556.5 1599.3 1813.3 1963.2 7708.8 8342.3 8570.4 8572.2 8662.7 8412.2 8628.5 8637.0 8641.6 8650.0 2343.5 2377.3 2379.2 2382.0 2384.3 2338.8 2357.7 2380.7 2384.4 2388.9 2341.4 2359.0 2368.0 2378.9 2380.7 1983.7 2310.8 2337.4 2340.2 2365.1 2330.3 2331.7 2333.1 2333.8 2336.7 8539.6 8558.8 8680.3 8681.1 8832.0 5271.8 5412.8 5419.0 5423.3 5428.4 3389.5 3516.9 3520.6 3540.2 3590.8 919.5 981.0 982.4 1022.8 1031.8 11356.3 11462.3 11468.5 11484.8 11490.4 4520.9 4550.2 4571.8 4624.3 4640.2 9072.5 9078.5 9083.5 9087.1 9097.6 5210.5 5212.7 5213.6 5221.9 5233.2 4389.0 4550.1 4701.0 4748.1 4857.1 3721.5 3745.9 3796.2 3826.9 3936.5 1050.4 1120.2 1188.1 1188.7 1205.2 811.1 863.3 902.1 904.7 949.9 5468.6 5621.9 5623.2 5695.7 5877.7 426.9 442.2 452.8 454.2 460.9 3777.4 3803.6 3813.9 3881.8 3891.6 10254.3 10516.7 10660.1 10669.0 10694.8 10582.6 10614.9 10664.5 10686.9 10705.0 6891.8 7865.5 8115.7 8167.4 8167.5 5612.2 5634.7 5832.4 5916.8 5918.9 9501.3 9980.0 9982.3 9982.4 10003.3 9722.9 10021.8 10030.4 10046.2 10137.3 6584.9 6780.3 6781.4 7390.7 7518.1 8231.4 8294.9 8381.1 8396.7 8408.1 7295.1 7381.0 7384.2 7514.5 7542.1 7246.6 7267.5 7306.9 7365.8 7407.2 6755.7 6779.4 6801.5 6858.7 6869.3 4757.8 4765.2 4836.1 4897.5 5035.4 4803.7 4841.4 4848.5 4911.0 5068.7 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4499.09 Median:5070.06 stddev:1396.84 (27.6%) stddev-sans-first:1364.34 01:49:20 INFO - PROCESS | 1668 | Values: 277.3 308.3 313.5 316.6 319.9 4943.9 5289.7 5344.6 5365.0 5376.4 4137.6 5007.0 5173.8 5236.1 5384.2 3467.1 3862.0 3865.4 3976.4 4115.3 3392.6 3605.2 3767.6 4075.2 4098.6 3866.7 3980.8 4046.6 4052.5 4076.4 3737.7 3837.6 3962.8 4013.6 4039.7 3513.6 3515.4 3520.0 3537.0 3539.4 4215.0 4222.5 5024.2 5045.8 5053.2 3920.5 4601.4 4624.9 4636.3 4639.9 4939.9 5138.8 5210.9 5609.6 5618.6 3260.9 3273.3 3472.3 3654.7 3820.4 5145.8 5156.6 5435.8 5827.6 5937.6 3839.8 3849.9 3854.5 3877.1 3880.8 3940.2 3943.5 3944.1 3947.3 3966.7 4386.5 4540.5 4584.0 4592.7 4597.0 5234.7 5265.1 5314.0 5622.4 5667.3 1300.5 1338.4 1339.3 1340.5 1347.5 788.4 808.0 823.9 828.8 836.7 4429.6 5084.9 5090.4 5205.1 5238.1 5051.5 5192.5 5208.4 5761.0 5800.7 4358.9 4597.4 4891.4 5210.7 5237.8 2806.3 2842.7 2860.4 2894.0 3016.4 4823.0 5145.7 5234.4 5597.3 5993.4 4888.5 5669.9 5928.9 5956.2 6015.6 5010.9 5787.0 5793.5 6130.1 6135.9 5160.9 5180.8 5644.8 5853.3 5897.4 5776.3 5784.0 5784.3 5792.9 5805.0 5634.6 6071.6 6110.6 6110.7 6146.8 3573.6 3679.6 3899.9 3901.8 3908.2 5276.2 5531.6 5812.6 5833.7 5872.8 5055.2 5591.5 5622.8 5636.7 5652.4 4612.7 5637.2 5642.3 5646.4 5670.3 4769.4 5540.2 5547.7 5564.5 5574.2 5099.2 5340.7 5386.1 5611.5 5731.8 4708.7 5383.6 5472.8 5517.7 5542.3 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:17024.17 Median:641.71 stddev:23810.68 (3710.5%) stddev-sans-first:23845.58 01:49:20 INFO - PROCESS | 1668 | Values: 609.8 630.7 662.6 741.7 787.1 14589.0 14754.7 14943.6 15021.4 15027.8 13908.8 13935.3 13944.7 13967.3 13973.6 718.1 734.5 871.8 886.5 912.7 891.5 903.8 905.9 907.0 908.0 957.3 958.1 958.6 961.2 961.5 738.3 905.7 945.5 948.6 954.0 708.9 709.1 710.4 712.0 712.4 14422.6 14452.1 14452.6 14464.9 14490.9 12006.9 12099.5 12164.1 12343.3 12453.2 10652.1 10709.8 10733.9 11052.9 11105.5 3085.9 3354.7 3543.9 3931.0 4283.6 65216.7 67745.7 77029.0 80466.6 80789.4 9245.6 9342.0 9377.0 9457.0 9853.4 19073.4 19286.0 19335.6 19336.8 19370.1 7309.0 7344.7 7384.0 7636.2 7724.8 3803.2 3960.0 3969.3 3994.4 4000.7 2982.4 3587.3 3619.6 3643.1 3654.4 652.7 654.3 656.0 658.7 667.3 477.5 519.5 538.8 563.1 585.5 6171.3 7269.2 7369.9 7621.1 7967.1 498.9 523.4 542.5 567.2 584.9 3169.9 3723.6 3774.8 3823.3 4004.5 80662.0 81978.2 84223.6 85551.0 86022.0 75274.0 75289.0 75598.0 76105.6 76709.0 67927.6 68159.0 68385.0 68608.7 68976.3 7481.9 7494.6 7548.7 7620.7 7646.5 36906.6 41332.4 43179.6 44162.8 44287.6 60793.4 60857.1 60894.4 60933.1 60978.7 9716.7 9848.8 9880.3 9925.6 10149.4 10678.8 11072.8 11075.9 11151.4 11592.2 9847.2 10332.7 10339.5 10371.5 10375.8 8805.5 10446.1 10503.9 10514.1 10547.4 9893.7 10022.0 10267.6 10320.2 10326.9 8561.2 8573.4 8738.0 8836.0 8946.7 8507.0 8573.8 8678.0 8717.2 8721.4 01:49:20 INFO - PROCESS | 1668 | -------- Summary: end -------- 01:49:20 INFO - PROCESS | 1668 | 01:49:20 INFO - TEST-INFO | 1668: exit 0 01:49:20 INFO - TEST-OK | dromaeo_css | took 1116048ms 01:49:20 INFO - TEST-START | kraken 01:49:20 INFO - operating with platform_type : mac_ 01:49:20 INFO - Initialising browser for kraken test... 01:49:20 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpFYDbx2/profile http://localhost:49275/getInfo.html) 01:49:23 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 01:49:23 INFO - PROCESS | 1674 | colorDepth:24 01:49:23 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 01:49:23 INFO - PROCESS | 1674 | __metrics 01:49:23 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:49:23 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 01:49:23 INFO - TEST-INFO | 1674: exit 0 01:49:23 INFO - Browser initialized. 01:49:23 INFO - Running cycle 1/1 for kraken test... 01:49:23 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpFYDbx2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:49:35 INFO - PROCESS | 1675 | RSS: Main: 240943104 01:49:35 INFO - PROCESS | 1675 | 01:49:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 01:49:39 INFO - PROCESS | 1675 | RSS: Main: 288780288 01:49:39 INFO - PROCESS | 1675 | 01:49:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 01:49:42 INFO - PROCESS | 1675 | RSS: Main: 297373696 01:49:42 INFO - PROCESS | 1675 | 01:49:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 01:49:46 INFO - PROCESS | 1675 | RSS: Main: 387399680 01:49:46 INFO - PROCESS | 1675 | 01:49:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 01:49:49 INFO - PROCESS | 1675 | RSS: Main: 285548544 01:49:49 INFO - PROCESS | 1675 | 01:49:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:49:52 INFO - PROCESS | 1675 | RSS: Main: 289894400 01:49:52 INFO - PROCESS | 1675 | 01:49:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 01:49:55 INFO - PROCESS | 1675 | RSS: Main: 328200192 01:49:55 INFO - PROCESS | 1675 | 01:49:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 01:49:59 INFO - PROCESS | 1675 | RSS: Main: 327991296 01:49:59 INFO - PROCESS | 1675 | 01:50:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 01:50:02 INFO - PROCESS | 1675 | RSS: Main: 327188480 01:50:02 INFO - PROCESS | 1675 | 01:50:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:50:04 INFO - PROCESS | 1675 | RSS: Main: 314777600 01:50:04 INFO - PROCESS | 1675 | 01:50:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:50:07 INFO - PROCESS | 1675 | RSS: Main: 321310720 01:50:07 INFO - PROCESS | 1675 | 01:50:09 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:50:10 INFO - PROCESS | 1675 | RSS: Main: 323604480 01:50:10 INFO - PROCESS | 1675 | 01:50:12 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:50:13 INFO - PROCESS | 1675 | RSS: Main: 328085504 01:50:13 INFO - PROCESS | 1675 | 01:50:16 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:50:17 INFO - PROCESS | 1675 | RSS: Main: 327270400 01:50:17 INFO - PROCESS | 1675 | 01:50:18 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 01:50:19 INFO - PROCESS | 1675 | RSS: Main: 374136832 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | __start_tp_report 01:50:19 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 01:50:19 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 01:50:19 INFO - PROCESS | 1675 | |i|pagename|runs| 01:50:19 INFO - PROCESS | 1675 | |0;ai-astar;111;106;112;112;113;112;112;112;112;111 01:50:19 INFO - PROCESS | 1675 | |1;audio-beat-detection;150;114;114;166;115;106;137;117;149;115 01:50:19 INFO - PROCESS | 1675 | |2;audio-dft;183;187;182;185;185;183;180;182;182;184 01:50:19 INFO - PROCESS | 1675 | |3;audio-fft;80;57;64;109;84;79;65;55;97;57 01:50:19 INFO - PROCESS | 1675 | |4;audio-oscillator;87;69;100;71;70;69;94;92;100;135 01:50:19 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;85;91;88;88;85;90;87;89;86 01:50:19 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;95;95;95;104;149;118;94;95;94 01:50:19 INFO - PROCESS | 1675 | |7;imaging-desaturate;86;86;87;86;86;84;83;84;84;86 01:50:19 INFO - PROCESS | 1675 | |8;json-parse-financial;59;58;57;57;58;58;59;76;55;56 01:50:19 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;66;65;98;115;107;77;73;72;71;71 01:50:19 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;86;85;87;84;86;85;81;84;84;87 01:50:19 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;140;137;139;139;141;139;154;145;198;141 01:50:19 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;167;165;164;168;164;165;161;213;163;161 01:50:19 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;65;67;67;75;87;74;66;66;66;66 01:50:19 INFO - PROCESS | 1675 | __end_tp_report 01:50:19 INFO - PROCESS | 1675 | __start_cc_report 01:50:19 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1954 01:50:19 INFO - PROCESS | 1675 | __end_cc_report 01:50:19 INFO - PROCESS | 1675 | __startTimestamp1464252619115__endTimestamp 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | ------- Summary: start ------- 01:50:19 INFO - PROCESS | 1675 | Number of tests: 14 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:1.95 (1.7%) stddev-sans-first:2.06 01:50:19 INFO - PROCESS | 1675 | Values: 111.0 106.0 112.0 112.0 113.0 112.0 112.0 112.0 112.0 111.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:127.00 stddev:20.51 (16.1%) stddev-sans-first:20.19 01:50:19 INFO - PROCESS | 1675 | Values: 150.0 114.0 114.0 166.0 115.0 106.0 137.0 117.0 149.0 115.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:2.00 (1.1%) stddev-sans-first:2.12 01:50:19 INFO - PROCESS | 1675 | Values: 183.0 187.0 182.0 185.0 185.0 183.0 180.0 182.0 182.0 184.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:74.70 Median:72.00 stddev:18.35 (25.5%) stddev-sans-first:19.36 01:50:19 INFO - PROCESS | 1675 | Values: 80.0 57.0 64.0 109.0 84.0 79.0 65.0 55.0 97.0 57.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:88.70 Median:70.50 stddev:20.76 (29.5%) stddev-sans-first:22.01 01:50:19 INFO - PROCESS | 1675 | Values: 87.0 69.0 100.0 71.0 70.0 69.0 94.0 92.0 100.0 135.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.50 stddev:2.04 (2.3%) stddev-sans-first:2.12 01:50:19 INFO - PROCESS | 1675 | Values: 89.0 85.0 91.0 88.0 88.0 85.0 90.0 87.0 89.0 86.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:94.50 stddev:17.76 (18.8%) stddev-sans-first:18.52 01:50:19 INFO - PROCESS | 1675 | Values: 94.0 95.0 95.0 95.0 104.0 149.0 118.0 94.0 95.0 94.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.32 (1.5%) stddev-sans-first:1.36 01:50:19 INFO - PROCESS | 1675 | Values: 86.0 86.0 87.0 86.0 86.0 84.0 83.0 84.0 84.0 86.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:59.30 Median:58.00 stddev:6.00 (10.3%) stddev-sans-first:6.36 01:50:19 INFO - PROCESS | 1675 | Values: 59.0 58.0 57.0 57.0 58.0 58.0 59.0 76.0 55.0 56.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:81.50 Median:71.50 stddev:18.14 (25.4%) stddev-sans-first:18.35 01:50:19 INFO - PROCESS | 1675 | Values: 66.0 65.0 98.0 115.0 107.0 77.0 73.0 72.0 71.0 71.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:85.50 stddev:1.79 (2.1%) stddev-sans-first:1.86 01:50:19 INFO - PROCESS | 1675 | Values: 86.0 85.0 87.0 84.0 86.0 85.0 81.0 84.0 84.0 87.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:141.00 stddev:18.46 (13.1%) stddev-sans-first:19.39 01:50:19 INFO - PROCESS | 1675 | Values: 140.0 137.0 139.0 139.0 141.0 139.0 154.0 145.0 198.0 141.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:165.00 stddev:15.59 (9.4%) stddev-sans-first:16.52 01:50:19 INFO - PROCESS | 1675 | Values: 167.0 165.0 164.0 168.0 164.0 165.0 161.0 213.0 163.0 161.0 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:67.00 stddev:6.97 (10.4%) stddev-sans-first:7.16 01:50:19 INFO - PROCESS | 1675 | Values: 65.0 67.0 67.0 75.0 87.0 74.0 66.0 66.0 66.0 66.0 01:50:19 INFO - PROCESS | 1675 | -------- Summary: end -------- 01:50:19 INFO - PROCESS | 1675 | 01:50:19 INFO - TEST-INFO | 1675: exit 0 01:50:19 INFO - TEST-OK | kraken | took 59205ms 01:50:19 INFO - SUITE-END | took 1175s 01:50:20 INFO - Completed test suite (00:19:35) 01:50:20 INFO - javascript benchmark 01:50:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3431.8455669494874, 3469.8091604961733, 3482.181687303484, 3505.421605435103, 3508.245727168658, 5618.016847051765, 5623.915641265385, 5654.236678048463, 5724.656520608761, 5739.167820666006, 5664.830055098343, 5710.000749868777, 5782.595218334708, 5785.5082318002915, 5812.738426770795, 5101.464346243647, 5128.333316668846, 5200.08998425276, 5219.399769026572, 5276.076686579833, 5247.7900883964785, 5291.708956007418, 5302.761375738101, 5319.7340132993195, 5339.332583427072, 5102.36220472441, 5111.4121875984365, 5120.308758317617, 5130.897382052338, 5216.921746173792, 5148.047611191904, 5154.098032844237, 5160.380754309469, 5181.4559471255725, 5187.974060129675, 5144.279800827887, 5181.974090129563, 5185.351831021122, 5190.455002224788, 5268.394134674523, 5512.448755124496, 5586.944130558683, 5633.8591535211535, 5649.576281778883, 5678.914816277759, 6223.968880155616, 6264.060390941349, 6264.310925798159, 6270.905936410957, 6273.466755325757, 6256.343083976162, 6305.243370795534, 6319.842003949892, 6327.303996560375, 6334.904976425358, 5662.0, 5751.137329400582, 5774.018416869142, 5780.508656764138, 5820.796272130455, 6278.0, 6289.433950944345, 6299.559030867796, 6309.968450157811, 6312.779052733133, 6118.847028824226, 6132.702084519407, 6138.772245550907, 6149.7847575335545, 6167.444929956326, 6084.4828189603495, 6199.0, 6245.875082498324, 6255.0, 6316.4315211630255, 6164.383561643889, 6204.286507051746, 6216.4405203531, 6230.688465576703, 6234.750609975641, 5917.112433134978, 6180.443760061616, 6232.532560058014, 6273.435390814849, 6282.0, 4762.618990480753, 4800.35195248637, 4819.734914579732, 4877.7073375596865, 4913.533214344631, 4376.1685279797375, 4392.1874453226255, 4401.581849724271, 4426.756528390905, 4461.0, 5012.448630650625, 5749.482546570745, 5768.307803063575, 5778.682172480554, 5830.300363956352, 5492.736670565792, 5889.38161493037, 6157.538184636171, 6168.691565421711, 6308.337624549448, 4754.358161648133, 5714.942850571441, 5715.057015592381, 5725.08398656213, 5730.971345143331, 5284.603654725834, 5371.75827087782, 5375.354957405137, 5375.9462405376225, 5380.596455265871, 6196.10156527301, 6254.655993920378, 6266.060090986389, 6279.403456671609, 6302.338254483213, 6302.71637776292, 6309.652969086745, 6314.400131987454, 6333.113364129118, 6333.524985626004, 6300.684965751786, 6317.336679648754, 6324.525660575568, 6327.90508142369, 6333.8733225336155, 6047.48596369296, 6109.144719739151, 6112.929053818819, 6124.533510047433, 6131.84670383244, 6264.906026409516, 6291.811245662638, 6308.716107775253, 6309.652969086561, 6313.526485513513, 6043.063325184604, 6063.545234107548, 6072.544559157993, 6084.300305464928, 6085.421884920926, 5715.371309156073, 5927.9703601481715, 5990.891685038282, 6032.426919442646, 6063.0, 5930.169776231417, 5941.1088336749835, 5953.493953014043, 5991.940080599138, 6018.458338749533, 5081.364829396325, 6079.787207447718, 6081.695915204134, 6137.539684523768, 6142.9078563820685, 5754.050581654147, 5821.301443826768, 5826.94173058264, 5879.412058794086, 5897.115432685132], "value": 5677.423468417796, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.8339731285988, 523.8716514453957, 532.2893936594646, 551.7778121460963, 565.0535353283251, 30165.94419195326, 30887.764489420424, 40782.0, 41345.17309653805, 42276.57723422773, 40618.390724139164, 41306.38040429396, 41470.58529414705, 43181.78409107958, 43494.13011739763, 9020.54897255138, 9078.909210907905, 9762.26782991275, 9767.0, 9787.755306117368, 8651.870221946676, 11613.941930290379, 11804.999999999956, 11806.291622502677, 12116.454759535798, 11769.823452648174, 11880.40597970098, 11899.7620047599, 11901.464434100442, 11909.106816988717, 11822.58620948267, 11920.701982450422, 11935.761284774257, 11938.761224775542, 11953.521859125669, 11782.646520604396, 11887.10846686502, 11906.58326958561, 11916.880831191664, 11939.641810745692, 39879.0, 40124.59875401267, 40176.59823401787, 40176.995575110566, 40269.59730402717, 3125.5780469636534, 3174.5873036505336, 3180.3162320101287, 3269.1500209945348, 3364.394409006378, 24535.63196552053, 28457.14628561126, 28537.429251414855, 29499.70500294991, 29536.26159346012, 2698.0407796998934, 2714.443539074485, 2731.235254128847, 2738.698743138253, 2936.0164344944255, 505422.0, 507527.0, 507656.0, 507748.0, 507748.0, 56240.0, 56994.43005569974, 57004.0, 57157.714211428676, 57194.71402643043, 63056.68471657704, 63145.36854631395, 63160.684196578724, 63228.683856581345, 63342.36657633457, 15853.762193567105, 16277.918610406872, 16283.674326513403, 16300.347986080424, 16304.429344972868, 11484.196106272648, 11748.236364636272, 12710.555130570385, 12754.61736147917, 12785.808212876815, 15518.60332570074, 15679.588837004725, 15873.761893571605, 15969.520914372355, 16106.30883757212, 6561.901571476432, 6567.310432404529, 6598.934010659832, 6647.069410282565, 6654.23476300222, 880.6477409036437, 1072.672834785398, 1073.3291692692067, 1075.1828610256262, 1076.9946150269197, 28571.0, 28698.713012870023, 28835.0, 29290.0, 29490.557641635394, 1039.6517166749072, 1057.5293994172519, 1079.4494807648, 1129.717570607348, 1168.8831116888243, 13309.93345033288, 14767.483138090116, 14841.109533428029, 15309.234538273042, 15315.387384504495, 185423.0, 188987.0, 189126.0, 189335.0, 189663.0, 59987.400125999055, 60045.69977150086, 60177.79644407175, 60227.39772602218, 60239.698801505714, 1292.3925754895183, 1419.4180386041467, 1448.833384160814, 1467.713795809807, 1474.395497845878, 27608.585871212348, 29735.405291894473, 29933.251668708457, 30192.5471117929, 30501.389972199988, 41587.7920610395, 48527.5147248523, 48961.51038489569, 49523.50476495333, 49609.0, 489622.0, 492140.0, 492259.0, 492948.0, 492992.0, 31372.0, 32991.175220619676, 33773.83113084419, 39051.82844514669, 39698.206035879695, 44927.32609010802, 45160.0, 45492.772536137105, 45987.08025839531, 46419.76790116028, 37494.0, 37526.62473375232, 37587.62412375951, 38018.61981380151, 38299.23401532009, 36384.636153639185, 37023.81488092542, 37513.437298439996, 37698.81150594229, 37953.81023094977, 36317.0, 36374.81812591026, 36790.8160459196, 37344.62655373412, 37755.05612359713, 24377.878110609334, 31799.68200317967, 32776.0, 32779.34441311208, 32980.1754956128, 20200.797992020478, 24012.87993560021, 24681.506369872863, 24917.501649966536, 25170.24489265325], "value": 19303.633370151594, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.3583783001303, 387.2835254778659, 388.60168327464345, 399.57245747050666, 406.0073121218622, 19517.512062198453, 21041.789582104175, 21202.46993825155, 21325.93370331485, 22381.88090595472, 16178.514644560682, 20724.171033158695, 21275.829829359416, 21688.457788555294, 21785.782142178574, 5320.361556613192, 5691.886162276742, 5857.443542863442, 6144.7542098316235, 6153.969230153843, 5855.180274761516, 5991.400859914017, 6062.727177277011, 6113.510919126482, 6119.112728654342, 6054.788082417116, 6062.848428789293, 6072.997955337386, 6082.665453400061, 6084.08738689198, 5951.613145145595, 6032.607880487776, 6058.848528786772, 6062.181605483247, 6077.088436734481, 5110.463401342842, 5258.894822103574, 5300.151975683911, 5306.946930530684, 5314.495122963351, 26042.34894127643, 26911.730882691118, 26921.0, 27066.45867082667, 27305.180844574694, 2047.8873661948587, 2106.504971331751, 2202.9008694608783, 2241.6637504374476, 2327.371609665398, 9330.58012389444, 14630.487932922402, 14788.70422591553, 15015.324310406058, 15622.218889055503, 1683.6969345517803, 1713.8691488675947, 1780.5993651428455, 1799.8750780762023, 1837.4946889605333, 104982.0, 118928.40535797352, 127382.3630881849, 128145.82614651686, 128635.99999999907, 11495.88504114965, 11510.136739744552, 11648.708782280512, 11652.76694466106, 11817.468213930222, 14792.778108328384, 14890.553283401516, 14927.029743066883, 14942.477013304482, 14944.0, 8475.364347673949, 8605.612747426381, 8621.741347759576, 8741.213290803731, 8741.431806932529, 8542.829143417097, 8573.22840944318, 8579.35654825878, 8693.174148455886, 8706.782330441663, 4506.324051392317, 4597.678162528591, 4612.3542704021465, 4636.79134438951, 4680.836170734076, 1923.6633589121848, 1945.4844466216464, 1955.1495099631707, 1959.6080783843288, 1960.1865225931365, 894.6600291889171, 903.1781079217881, 930.776613612728, 951.6954574536082, 959.5154447004358, 20061.602256755687, 20848.895755521124, 20869.1652333908, 20954.057067432, 20960.895195523924, 890.3500444675424, 900.0774206438452, 925.2829057480507, 941.8116376724546, 962.8844538655406, 9209.125133112344, 9337.859932101024, 10716.892831071746, 10851.372294155939, 11040.116790656888, 573876.0, 574198.0, 585666.0, 597529.0, 597851.0, 122838.0, 123222.76777232293, 125403.0, 125851.0, 127612.0, 1002.979940401188, 1009.6466236817203, 1034.5913364221242, 1057.3285963413134, 1060.3319908457622, 15308.464203752816, 15309.08145511273, 17677.823221767616, 18024.549386265448, 18275.634487310446, 47227.0, 48817.755911220214, 49922.500774991786, 50861.49138508715, 52104.0, 58025.0, 63767.0, 67063.66468167628, 74736.25263747294, 76617.23382766101, 8104.756857294291, 8141.389395795458, 8143.79640508992, 8297.128801475757, 8300.70947516834, 11179.552817887194, 12288.385580721108, 12525.37373131358, 12607.495700172258, 12680.365981700694, 10623.89376106229, 10788.89928281866, 10813.351198928089, 10999.750436735712, 11074.335539867634, 7253.057102576631, 10912.290701104403, 10969.50637221343, 11254.099672026054, 11279.210455268052, 7269.491135620456, 10650.8402373966, 11257.324560526395, 11318.830217546578, 11486.425678716198, 7995.520268783892, 8112.148224436585, 8137.186281371815, 8319.043310019426, 8426.368022398223, 10460.0, 10932.344059356146, 11194.0, 11210.775784484427, 11259.493322800658], "value": 9554.728648793314, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.0101928649947, 1556.5096994446749, 1599.2563457992032, 1813.3381315819722, 1963.1950900130948, 7708.845823083535, 8342.332613390929, 8570.400071994964, 8572.185642363966, 8662.74011779646, 8412.1587841216, 8628.525431101287, 8637.0, 8641.611127499262, 8649.956750216255, 2343.5430091132193, 2377.286813955815, 2379.167291447988, 2381.988090059547, 2384.332386931662, 2338.7661233876647, 2357.6581395697613, 2380.702412198475, 2384.415818124562, 2388.916387926423, 2341.4029422497324, 2358.9646155307682, 2368.0, 2378.940526486834, 2380.7143142822883, 1983.6528607493776, 2310.803581695541, 2337.3572267626455, 2340.1809366721595, 2365.077619728307, 2330.3358542815217, 2331.7318508371586, 2333.1134169015663, 2333.754955729658, 2336.719593648756, 8539.615717292736, 8558.828823423497, 8680.30557555394, 8681.088485708973, 8831.955840220822, 5271.841844744664, 5412.783488660449, 5418.972905135489, 5423.2949716537, 5428.37573679028, 3389.5254664347012, 3516.8769093081874, 3520.647935206459, 3540.185757275841, 3590.7845529268066, 919.4804935211585, 980.9705708828747, 982.3860087445347, 1022.7749895023084, 1031.8349064149775, 11356.26184298018, 11462.312261264351, 11468.4839182237, 11484.827727584094, 11490.36802975836, 4520.864374068718, 4550.203714349976, 4571.839985600488, 4624.260118381043, 4640.164770341371, 9072.50101244438, 9078.500682462529, 9083.500407477652, 9087.136722011399, 9097.590608422637, 5210.452902445264, 5212.739363031833, 5213.556847667915, 5221.921671174935, 5233.241180028875, 4389.012472193731, 4550.13547425988, 4700.976495117571, 4748.050389922029, 4857.077155340474, 3721.4603882436895, 3745.887623371303, 3796.1838204786104, 3826.885193444201, 3936.527616685959, 1050.3960222871879, 1120.176670147441, 1188.0990415335566, 1188.7028242939266, 1205.1624121235657, 811.0591713612299, 863.2834747159842, 902.0888902208816, 904.6878826804742, 949.8907625623003, 5468.589855760833, 5621.887562248732, 5623.212750214973, 5695.686737229409, 5877.676727780014, 426.8932766808298, 442.20182570459986, 452.7532494790347, 454.1530046463322, 460.8663487588562, 3777.3956167013694, 3803.6196380362294, 3813.923721525554, 3881.825317860703, 3891.552471465761, 10254.333468324536, 10516.737081573021, 10660.09389201911, 10669.0, 10694.786104278026, 10582.629607963685, 10614.893851061544, 10664.520096595672, 10686.946565267279, 10705.0, 6891.827704307332, 7865.488743231786, 8115.675372985014, 8167.42828002046, 8167.509949402936, 5612.158176273591, 5634.690092044977, 5832.416758324133, 5916.792912248051, 5918.905002574537, 9501.28740344463, 9979.950100249453, 9982.251331150337, 9982.35114717541, 10003.34978226413, 9722.902770972483, 10021.849672255068, 10030.448325342177, 10046.196304295492, 10137.290389672653, 6584.868302633825, 6780.254172040973, 6781.355771201728, 7390.6674199660065, 7518.060242469692, 8231.42380033392, 8294.917050829414, 8381.078081411162, 8396.664133434839, 8408.117147699404, 7295.124585049828, 7380.96309518449, 7384.224656410999, 7514.511556748794, 7542.132654744555, 7246.6376681166785, 7267.527610705287, 7306.890396644161, 7365.7790266292095, 7407.1852096268285, 6755.729770809309, 6779.389854913082, 6801.489888258499, 6858.691358888762, 6869.313068693091, 4757.7621118943225, 4765.189917714067, 4836.105320515716, 4897.510248975074, 5035.420926593342, 4803.663743537919, 4841.370621819141, 4848.515148485264, 4911.042346742351, 5068.670536415121], "value": 4407.447658027051, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.3440812478489, 308.33246022361584, 313.5218791343203, 316.5615622363027, 319.8912369794271, 4943.876403089925, 5289.704022514489, 5344.583685323388, 5365.0224125610275, 5376.408595054546, 4137.572537474566, 5007.0486607544635, 5173.801134517825, 5236.083685355066, 5384.1923711443305, 3467.127751014449, 3861.9572715366835, 3865.439511270864, 3976.4234186043004, 4115.259253334399, 3392.643772403899, 3605.1708107135373, 3767.566729826078, 4075.1645912587846, 4098.5901409858925, 3866.6906647468136, 3980.800959952005, 4046.6358027777496, 4052.49343832021, 4076.388541718751, 3737.6636102750613, 3837.635424634669, 3962.8216730247063, 4013.57857424972, 4039.7172197946156, 3513.5959364672876, 3515.418061872168, 3519.9616113246652, 3536.964630353715, 3539.43369060949, 4215.0, 4222.535521092677, 5024.246363045572, 5045.772940217662, 5053.216751403538, 3920.490336256297, 4601.378813860119, 4624.930626040578, 4636.258198688193, 4639.86080417588, 4939.900542269521, 5138.7687554059785, 5210.895782084375, 5609.635373700697, 5618.578606604521, 3260.9184770380693, 3273.29624130813, 3472.2881809229048, 3654.7441679082476, 3820.4460353248624, 5145.84562463123, 5156.581939966539, 5435.809746658848, 5827.592068555203, 5937.643741375531, 3839.752080573769, 3849.8845034648443, 3854.475318666278, 3877.1470276539117, 3880.8447662093763, 3940.2119576084897, 3943.495880116147, 3944.1322908959983, 3947.2500224957166, 3966.7421617594764, 4386.451693538308, 4540.545945405433, 4583.977080114578, 4592.724436533752, 4597.034622729264, 5234.67057159568, 5265.05085219892, 5314.0, 5622.409646987007, 5667.291588551431, 1300.5253082624963, 1338.4244774746758, 1339.270097796703, 1340.4571148684797, 1347.5148946379295, 788.3929374381694, 807.9878801817978, 823.8805373220849, 828.846663367279, 836.690579243482, 4429.601335879722, 5084.898302033938, 5090.389153301628, 5205.06308864408, 5238.109521381374, 5051.51445502602, 5192.507596353771, 5208.40619972218, 5760.971195143998, 5800.709964501758, 4358.869233922987, 4597.402467605792, 4891.437484689306, 5210.697325668583, 5237.790488380422, 2806.2984253936515, 2842.701516340796, 2860.3850172213074, 2893.958175056978, 3016.441958237733, 4823.0, 5145.665531740425, 5234.42421333653, 5597.3132896210045, 5993.430624090704, 4888.462269150391, 5669.922714684197, 5928.881422371528, 5956.15827791081, 6015.586337210752, 5010.9248361273885, 5786.987277226469, 5793.536517078708, 6130.149480491504, 6135.938640613536, 5160.948390516047, 5180.8137995133475, 5644.8306550803545, 5853.2976042875125, 5897.3807750187325, 5776.277965254343, 5784.021229067118, 5784.305883294031, 5792.884142317046, 5805.0, 5634.6337488063145, 6071.632063495152, 6110.602810817282, 6110.7250173741395, 6146.815595532141, 3573.5533058367705, 3679.5695857657224, 3899.9275199320414, 3901.7854018028147, 3908.1988192421054, 5276.15581506957, 5531.585131115102, 5812.564057695605, 5833.737481813244, 5872.794452194153, 5055.216441451581, 5591.4688104629995, 5622.8313150605545, 5636.718164091697, 5652.406497317723, 4612.746298953455, 5637.182608521659, 5642.266505354278, 5646.407127251744, 5670.262865827416, 4769.379980602499, 5540.224368588481, 5547.722613869209, 5564.471375219509, 5574.247476590608, 5099.209622508517, 5340.706261155674, 5386.061018033583, 5611.494965453129, 5731.828045158652, 4708.693934894221, 5383.631202270988, 5472.808451704172, 5517.668939863461, 5542.2795036644975], "value": 4051.4198263456883, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.7652403824527, 630.7256343490585, 662.6057495790005, 741.7218543046357, 787.0830482487902, 14589.0, 14754.704905901875, 14943.626409339771, 15021.399144034252, 15027.774583381244, 13908.791368129487, 13935.303234838268, 13944.721105577908, 13967.301634918238, 13973.580792576213, 718.0629278791175, 734.5188901269677, 871.7951281448854, 886.5257087458227, 912.7079334612927, 891.4695756025154, 903.7605034665819, 905.8595917632778, 906.9637214511412, 907.9818403631923, 957.3155194036262, 958.1281034258826, 958.5878072428853, 961.1685891703661, 961.4615815143542, 738.2691135775597, 905.7011186308569, 945.5083356654501, 948.5684013773716, 954.0078318548701, 708.9397401220903, 709.1313141401793, 710.4458522352573, 712.0245263988369, 712.3909057755596, 14422.639434014129, 14452.060616059922, 14452.566423007327, 14464.927675361661, 14490.92754536231, 12006.939965300118, 12099.516019359215, 12164.14850960433, 12343.259404435763, 12453.153389541018, 10652.147828173727, 10709.78580428387, 10733.946330268376, 11052.94996975286, 11105.500252488657, 3085.891993780207, 3354.66453354665, 3543.9114022149392, 3930.960690393088, 4283.614474697292, 65216.673916630585, 67745.66127169383, 77029.0, 80466.59766701129, 80789.38421231601, 9245.583948722324, 9341.953290233505, 9377.0, 9456.952715236379, 9853.408795472295, 19073.427797166107, 19286.035698215033, 19335.613287734166, 19336.80663193378, 19370.128344224544, 7309.049823523014, 7344.669489872968, 7384.0, 7636.198199189005, 7724.806879828049, 3803.1633040730994, 3960.029792700749, 3969.273341300992, 3994.4407782910416, 4000.71246920058, 2982.4035192961496, 3587.300476407128, 3619.6199399063244, 3643.107438677541, 3654.415293553072, 652.6932341799346, 654.3162395296927, 655.9967200163968, 658.6781594877998, 667.2760055339913, 477.51770711581554, 519.4960887938769, 538.7710223155143, 563.1468325486891, 585.4672248254153, 6171.321154672983, 7269.20038795732, 7369.926300736924, 7621.085469743555, 7967.123616402191, 498.8752811797051, 523.3614989712545, 542.4710906865774, 567.222904620664, 584.868404608968, 3169.889053883103, 3723.6408710820897, 3774.7923864187733, 3823.25446537928, 4004.4593979812353, 80662.0, 81978.18021819825, 84223.5788821052, 85551.0, 86022.0, 75274.0, 75289.0, 75598.0, 76105.6194719034, 76709.0, 67927.64144717078, 68159.0, 68385.0, 68608.6569567159, 68976.31023689698, 7481.869820837161, 7494.587797671181, 7548.735794247395, 7620.733274335372, 7646.502977306457, 36906.63093369032, 41332.38001430041, 43179.568204317555, 44162.77918610515, 44287.55712442963, 60793.39206607877, 60857.08714369199, 60894.391056090644, 60933.086003710865, 60978.69510652418, 9716.708498745049, 9848.803023939337, 9880.25898057663, 9925.602975880884, 10149.441780702133, 10678.8398174029, 11072.833907491555, 11075.944620276847, 11151.442427878736, 11592.246503977214, 9847.162991145833, 10332.690019299433, 10339.534720937727, 10371.481425928825, 10375.844362334721, 8805.471671699719, 10446.112080473245, 10503.894961050291, 10514.053735163872, 10547.367157970546, 9893.703188904345, 10022.0, 10267.58929642806, 10320.225983051452, 10326.896731032593, 8561.22948934599, 8573.356998225034, 8738.0, 8835.955820220857, 8946.68686595966, 8507.0, 8573.828523429622, 8678.0, 8717.215450609476, 8721.433106848035], "value": 6550.689482359671, "unit": "score"}], "name": "dromaeo_css", "value": 7047.300769353781, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 112.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 114.0, 114.0, 166.0, 115.0, 106.0, 137.0, 117.0, 149.0, 115.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 182.0, 185.0, 185.0, 183.0, 180.0, 182.0, 182.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 64.0, 109.0, 84.0, 79.0, 65.0, 55.0, 97.0, 57.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 100.0, 71.0, 70.0, 69.0, 94.0, 92.0, 100.0, 135.0], "value": 88.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 91.0, 88.0, 88.0, 85.0, 90.0, 87.0, 89.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 104.0, 149.0, 118.0, 94.0, 95.0, 94.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 86.0, 84.0, 83.0, 84.0, 84.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 57.0, 58.0, 58.0, 59.0, 76.0, 55.0, 56.0], "value": 59.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 98.0, 115.0, 107.0, 77.0, 73.0, 72.0, 71.0, 71.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 84.0, 86.0, 85.0, 81.0, 84.0, 84.0, 87.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 139.0, 139.0, 141.0, 139.0, 154.0, 145.0, 198.0, 141.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 164.0, 168.0, 164.0, 165.0, 161.0, 213.0, 163.0, 161.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 75.0, 87.0, 74.0, 66.0, 66.0, 66.0, 66.0], "value": 69.9, "unit": "score"}], "name": "kraken", "value": 1474.6, "alertThreshold": 2.0}]} 01:50:20 INFO - Return code: 0 01:50:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 01:50:20 ERROR - # TBPL SUCCESS # 01:50:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:50:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:50:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:50:20 INFO - 2016-05-26 01:50:20.421 screenresolution[1679:10264] starting screenresolution argv=screenresolution get 01:50:20 INFO - 2016-05-26 01:50:20.424 screenresolution[1679:10264] Display 0: 1600x1200x32@60 01:50:20 INFO - 2016-05-26 01:50:20.430 screenresolution[1680:10267] starting screenresolution argv=screenresolution list 01:50:20 INFO - Available Modes on Display 0 01:50:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:50:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:50:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:50:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:50:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:50:20 INFO - Intel Iris: 01:50:20 INFO - Chipset Model: Intel Iris 01:50:20 INFO - Type: GPU 01:50:20 INFO - Bus: Built-In 01:50:20 INFO - VRAM (Dynamic, Max): 1536 MB 01:50:20 INFO - Vendor: Intel (0x8086) 01:50:20 INFO - Device ID: 0x0a2e 01:50:20 INFO - Revision ID: 0x0009 01:50:20 INFO - Displays: 01:50:20 INFO - Display: 01:50:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:50:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:50:20 INFO - Display Serial Number: 1600x1200 60 01:50:20 INFO - Main Display: Yes 01:50:20 INFO - Mirror: Off 01:50:20 INFO - Online: Yes 01:50:20 INFO - Rotation: Supported 01:50:20 INFO - Return code: 0 01:50:20 INFO - Running post-action listener: _resource_record_post_action 01:50:20 INFO - ##### 01:50:20 INFO - ##### Finished run-tests step (success) 01:50:20 INFO - ##### 01:50:20 INFO - Running post-run listener: _resource_record_post_run 01:50:20 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31587840; Write bytes: 693052416; Read time: 199; Write time: 1878 01:50:20 INFO - TinderboxPrint: CPU usage
53.8% 01:50:20 INFO - TinderboxPrint: I/O read bytes / time
31,587,840 / 199 01:50:20 INFO - TinderboxPrint: I/O write bytes / time
693,052,416 / 1,878 01:50:20 INFO - TinderboxPrint: CPU idle
2,237.8 (46.2%) 01:50:20 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 01:50:20 INFO - TinderboxPrint: CPU user
1,634.0 (33.7%) 01:50:20 INFO - TinderboxPrint: Swap in / out
425,545,728 / 0 01:50:20 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 380729856; Write bytes: 375019008; Read time: 28220; Write time: 753 01:50:20 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30662144; Write bytes: 293080576; Read time: 187; Write time: 1063 01:50:21 INFO - Running post-run listener: _upload_blobber_files 01:50:21 INFO - Blob upload gear active. 01:50:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:50:21 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 01:50:21 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'] 01:50:21 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 01:50:21 INFO - (blobuploader) - INFO - Open directory for files ... 01:50:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:50:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:50:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:50:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:50:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:50:22 INFO - (blobuploader) - INFO - Done attempting. 01:50:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:50:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:50:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:50:26 INFO - (blobuploader) - INFO - Done attempting. 01:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:50:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:50:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:50:29 INFO - (blobuploader) - INFO - Done attempting. 01:50:29 INFO - (blobuploader) - INFO - Iteration through files over. 01:50:29 INFO - Return code: 0 01:50:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:50:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:50:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2889adcc5bb4576a1073e90245695e69c6530b620becbccda9c8277fdfc920fe95b2e41765b34ccdf126e90dc198583b43848508e5cfc53866d7622beedbd419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1dfeef268f4703538d96f6aff149ef6ef1f76d260b11387b5defdcc98528e1d61bd3d35ea8bb08933383528b9b354b21575b4bfa59b02a1caf05fd5e893b198a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33aedfccf7205ecb52e38f7fa155b04a082b1d0e3f638830a08d70043a2a581708f9d21cb6383378e980dd0ecca68ce327d553c2b6b4b1b29d487f16048fe7b3"} 01:50:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:50:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:50:29 INFO - Contents: 01:50:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2889adcc5bb4576a1073e90245695e69c6530b620becbccda9c8277fdfc920fe95b2e41765b34ccdf126e90dc198583b43848508e5cfc53866d7622beedbd419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1dfeef268f4703538d96f6aff149ef6ef1f76d260b11387b5defdcc98528e1d61bd3d35ea8bb08933383528b9b354b21575b4bfa59b02a1caf05fd5e893b198a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33aedfccf7205ecb52e38f7fa155b04a082b1d0e3f638830a08d70043a2a581708f9d21cb6383378e980dd0ecca68ce327d553c2b6b4b1b29d487f16048fe7b3"} 01:50:29 INFO - Running post-run listener: copy_logs_to_upload_dir 01:50:29 INFO - Copying logs to upload dir... 01:50:29 INFO - mkdir: /builds/slave/test/build/upload/logs 01:50:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.602887 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-26 01:50:29.146476) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 01:50:29.149880) ========= 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.uVoi3Wk49R/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.iEihiLxCFs/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2889adcc5bb4576a1073e90245695e69c6530b620becbccda9c8277fdfc920fe95b2e41765b34ccdf126e90dc198583b43848508e5cfc53866d7622beedbd419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1dfeef268f4703538d96f6aff149ef6ef1f76d260b11387b5defdcc98528e1d61bd3d35ea8bb08933383528b9b354b21575b4bfa59b02a1caf05fd5e893b198a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33aedfccf7205ecb52e38f7fa155b04a082b1d0e3f638830a08d70043a2a581708f9d21cb6383378e980dd0ecca68ce327d553c2b6b4b1b29d487f16048fe7b3"} build_url:https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011070 build_url: 'https://queue.taskcluster.net/v1/task/D9fEPS2oRHaMOLSblaJmaA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2889adcc5bb4576a1073e90245695e69c6530b620becbccda9c8277fdfc920fe95b2e41765b34ccdf126e90dc198583b43848508e5cfc53866d7622beedbd419", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1dfeef268f4703538d96f6aff149ef6ef1f76d260b11387b5defdcc98528e1d61bd3d35ea8bb08933383528b9b354b21575b4bfa59b02a1caf05fd5e893b198a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33aedfccf7205ecb52e38f7fa155b04a082b1d0e3f638830a08d70043a2a581708f9d21cb6383378e980dd0ecca68ce327d553c2b6b4b1b29d487f16048fe7b3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 01:50:29.189473) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 01:50:29.189854) ========= 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.uVoi3Wk49R/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.iEihiLxCFs/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 01:50:29.238627) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 01:50:29.238975) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 01:50:29.254164) ========= ========= Total master_lag: 0.21 =========