builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0358 starttime: 1464673528.39 results: success (0) buildid: 20160530205547 builduid: 24a50bb8b1724a29ba7d9caa2938ebc1 revision: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.393455) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.393868) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.394147) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.417205) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.417508) ========= 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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-30 22:45:27-- 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% 181M=0s 2016-05-30 22:45:27 (181 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104842 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.542339) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.542659) ========= 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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.025236 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:28.592420) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-30 22:45:28.592854) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 --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 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3KMZ2Yawip/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.iB1nxoxpGC/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-30 22:45:27,567 truncating revision to first 12 chars 2016-05-30 22:45:27,567 Setting DEBUG logging. 2016-05-30 22:45:27,567 attempt 1/10 2016-05-30 22:45:27,567 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/42fab251fe11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 22:45:28,674 unpacking tar archive at: fx-team-42fab251fe11/testing/mozharness/ program finished with exit code 0 elapsedTime=1.387905 ========= master_lag: 2.93 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-05-30 22:45:32.914939) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:32.915275) ========= script_repo_revision: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:32.915717) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:32.916008) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 22:45:33.001425) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2016-05-30 22:45:33.001952) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3KMZ2Yawip/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.iB1nxoxpGC/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:45:32 INFO - MultiFileLogger online at 20160530 22:45:32 in /builds/slave/test 22:45:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:45:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:45:32 INFO - {'append_to_log': False, 22:45:32 INFO - 'base_work_dir': '/builds/slave/test', 22:45:32 INFO - 'blob_upload_branch': 'Fx-Team', 22:45:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:45:32 INFO - 'branch': 'Fx-Team', 22:45:32 INFO - 'buildbot_json_path': 'buildprops.json', 22:45:32 INFO - 'config_files': ('talos/mac_config.py',), 22:45:32 INFO - 'default_actions': ('clobber', 22:45:32 INFO - 'read-buildbot-config', 22:45:32 INFO - 'download-and-extract', 22:45:32 INFO - 'populate-webroot', 22:45:32 INFO - 'create-virtualenv', 22:45:32 INFO - 'install', 22:45:32 INFO - 'run-tests'), 22:45:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:45:32 INFO - 'download_minidump_stackwalk': True, 22:45:32 INFO - 'download_symbols': 'ondemand', 22:45:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:45:32 INFO - 'tooltool.py': '/tools/tooltool.py', 22:45:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:45:32 INFO - '/tools/misc-python/virtualenv.py')}, 22:45:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:45:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:45:32 INFO - 'installer_path': 'installer.exe', 22:45:32 INFO - 'log_level': 'info', 22:45:32 INFO - 'log_name': 'talos', 22:45:32 INFO - 'log_to_console': True, 22:45:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:45:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:45:32 INFO - 'opt_config_files': (), 22:45:32 INFO - 'pip_index': False, 22:45:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:45:32 INFO - 'cmd': ('bash', 22:45:32 INFO - '-c', 22:45:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:45:32 INFO - 'enabled': True, 22:45:32 INFO - 'halt_on_failure': False, 22:45:32 INFO - 'name': 'check_screen_resolution'},), 22:45:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:45:32 INFO - 'cmd': ('bash', 22:45:32 INFO - '-c', 22:45:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:45:32 INFO - 'enabled': True, 22:45:32 INFO - 'halt_on_failure': False, 22:45:32 INFO - 'name': 'check_screen_resolution'},), 22:45:32 INFO - 'run_cmd_checks_enabled': True, 22:45:32 INFO - 'sps_profile': False, 22:45:32 INFO - 'sps_profile_interval': 0, 22:45:32 INFO - 'suite': 'dromaeojs-e10s', 22:45:32 INFO - 'system_bits': '32', 22:45:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:45:32 INFO - 'title': 't-yosemite-r7-0358', 22:45:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:45:32 INFO - 'use_talos_json': True, 22:45:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:45:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:45:32 INFO - 'work_dir': 'build'} 22:45:32 INFO - ##### 22:45:32 INFO - ##### Running clobber step. 22:45:32 INFO - ##### 22:45:32 INFO - Running pre-action listener: _resource_record_pre_action 22:45:32 INFO - Running main action method: clobber 22:45:32 INFO - rmtree: /builds/slave/test/build 22:45:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:45:33 INFO - Running post-action listener: _resource_record_post_action 22:45:33 INFO - ##### 22:45:33 INFO - ##### Finished clobber step (success) 22:45:33 INFO - ##### 22:45:33 INFO - ##### 22:45:33 INFO - ##### Running read-buildbot-config step. 22:45:33 INFO - ##### 22:45:33 INFO - Running pre-action listener: _resource_record_pre_action 22:45:33 INFO - Running main action method: read_buildbot_config 22:45:33 INFO - Using buildbot properties: 22:45:33 INFO - { 22:45:33 INFO - "project": "", 22:45:33 INFO - "product": "firefox", 22:45:33 INFO - "script_repo_revision": "production", 22:45:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 22:45:33 INFO - "repository": "", 22:45:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 22:45:33 INFO - "buildid": "20160530205547", 22:45:33 INFO - "pgo_build": "False", 22:45:33 INFO - "basedir": "/builds/slave/test", 22:45:33 INFO - "buildnumber": 691, 22:45:33 INFO - "slavename": "t-yosemite-r7-0358", 22:45:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:45:33 INFO - "platform": "yosemite_r7", 22:45:33 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 22:45:33 INFO - "branch": "fx-team", 22:45:33 INFO - "repo_path": "integration/fx-team", 22:45:33 INFO - "revision": "42fab251fe111d5f891c9bde0ee1fb6f7f946a50", 22:45:33 INFO - "stage_platform": "macosx64", 22:45:33 INFO - "builduid": "24a50bb8b1724a29ba7d9caa2938ebc1", 22:45:33 INFO - "slavebuilddir": "test" 22:45:33 INFO - } 22:45:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 22:45:33 INFO - Running post-action listener: _resource_record_post_action 22:45:33 INFO - ##### 22:45:33 INFO - ##### Finished read-buildbot-config step (success) 22:45:33 INFO - ##### 22:45:33 INFO - ##### 22:45:33 INFO - ##### Running download-and-extract step. 22:45:33 INFO - ##### 22:45:33 INFO - Running pre-action listener: _resource_record_pre_action 22:45:33 INFO - Running main action method: download_and_extract 22:45:33 INFO - mkdir: /builds/slave/test/build/tests 22:45:33 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')]} 22:45:33 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:45:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:45:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 22:45:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:45:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:00 INFO - Downloaded 1199 bytes. 22:46:00 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 22:46:00 INFO - Using the following test package requirements: 22:46:00 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:46:00 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 22:46:00 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 22:46:00 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'jsshell-mac.zip'], 22:46:00 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 22:46:00 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 22:46:00 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 22:46:00 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 22:46:00 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 22:46:00 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 22:46:00 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 22:46:00 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:46: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')]} 22:46:00 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:46:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:46:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:46:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:02 INFO - Downloaded 18684709 bytes. 22:46:02 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'] 22:46:02 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 22:46:05 INFO - Return code: 0 22:46:05 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 22:46:05 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')]} 22:46:05 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:46:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:46:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 22:46:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:08 INFO - Downloaded 18684709 bytes. 22:46:08 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'] 22:46:08 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 22:46:11 INFO - Return code: 0 22:46:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:11 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:46:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:46:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 22:46:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:12 INFO - Downloaded 10834055 bytes. 22:46:12 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'] 22:46:12 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 22:46:13 INFO - Return code: 0 22:46:13 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')]} 22:46:13 INFO - https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:46:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:46:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:46:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 22:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S_609GmFQke5um3RFw96ug/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 22:46:17 INFO - Downloaded 110902438 bytes. 22:46:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:46:17 INFO - mkdir: /builds/slave/test/properties 22:46:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:46:17 INFO - Writing to file /builds/slave/test/properties/build_url 22:46:17 INFO - Contents: 22:46:17 INFO - build_url:https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 22:46:18 INFO - Running post-action listener: _resource_record_post_action 22:46:18 INFO - Running post-action listener: set_extra_try_arguments 22:46:18 INFO - ##### 22:46:18 INFO - ##### Finished download-and-extract step (success) 22:46:18 INFO - ##### 22:46:18 INFO - ##### 22:46:18 INFO - ##### Running populate-webroot step. 22:46:18 INFO - ##### 22:46:18 INFO - Running pre-action listener: _resource_record_pre_action 22:46:18 INFO - Running main action method: populate_webroot 22:46:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:46:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:46:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:46:18 INFO - '--tppagecycles', 22:46:18 INFO - '7'], 22:46:18 INFO - 'tests': ['tsvgm']}}, 22:46:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:46:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:46:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:46:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:46:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:46:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:46:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['damp', 'tps']}, 22:46:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'tests': ['damp', 'tps']}, 22:46:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['dromaeo_dom']}, 22:46:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:46:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['a11yr', 22:46:18 INFO - 'ts_paint', 22:46:18 INFO - 'tpaint', 22:46:18 INFO - 'sessionrestore', 22:46:18 INFO - 'sessionrestore_no_auto_restore', 22:46:18 INFO - 'tabpaint']}, 22:46:18 INFO - 'other-e10s': {'tests': ['a11yr', 22:46:18 INFO - 'ts_paint', 22:46:18 INFO - 'tpaint', 22:46:18 INFO - 'sessionrestore', 22:46:18 INFO - 'sessionrestore_no_auto_restore', 22:46:18 INFO - 'tabpaint']}, 22:46:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:46:18 INFO - 'ts_paint', 22:46:18 INFO - 'tpaint', 22:46:18 INFO - 'sessionrestore', 22:46:18 INFO - 'sessionrestore_no_auto_restore', 22:46:18 INFO - 'tabpaint']}, 22:46:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:46:18 INFO - 'ts_paint', 22:46:18 INFO - 'tpaint', 22:46:18 INFO - 'sessionrestore', 22:46:18 INFO - 'sessionrestore_no_auto_restore', 22:46:18 INFO - 'tabpaint']}, 22:46:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['a11yr', 22:46:18 INFO - 'ts_paint', 22:46:18 INFO - 'tpaint', 22:46:18 INFO - 'sessionrestore', 22:46:18 INFO - 'sessionrestore_no_auto_restore', 22:46:18 INFO - 'tabpaint']}, 22:46:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['a11yr', 22:46:18 INFO - 'ts_paint', 22:46:18 INFO - 'tpaint', 22:46:18 INFO - 'sessionrestore', 22:46:18 INFO - 'sessionrestore_no_auto_restore', 22:46:18 INFO - 'tabpaint']}, 22:46:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['tsvgx', 22:46:18 INFO - 'tsvgr_opacity', 22:46:18 INFO - 'tart', 22:46:18 INFO - 'tscrollx', 22:46:18 INFO - 'cart']}, 22:46:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:46:18 INFO - 'tsvgr_opacity', 22:46:18 INFO - 'tart', 22:46:18 INFO - 'tscrollx', 22:46:18 INFO - 'cart']}, 22:46:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'talos_options': ['--disable-e10s'], 22:46:18 INFO - 'tests': ['tp5o']}, 22:46:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'tests': ['tp5o']}, 22:46:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'talos_options': ['--disable-e10s', 22:46:18 INFO - '--xperf_path', 22:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:46:18 INFO - 'tests': ['tp5n']}, 22:46:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:46:18 INFO - 'talos_options': ['--xperf_path', 22:46:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:46:18 INFO - 'tests': ['tp5n']}}, 22:46:18 INFO - 'talos.zip': {'path': '', 22:46:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:46:18 INFO - Running post-action listener: _resource_record_post_action 22:46:18 INFO - ##### 22:46:18 INFO - ##### Finished populate-webroot step (success) 22:46:18 INFO - ##### 22:46:18 INFO - ##### 22:46:18 INFO - ##### Running create-virtualenv step. 22:46:18 INFO - ##### 22:46:18 INFO - Running pre-action listener: _resource_record_pre_action 22:46:18 INFO - Running main action method: create_virtualenv 22:46:18 INFO - Creating virtualenv /builds/slave/test/build/venv 22:46:18 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 22:46:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:46:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:46:18 INFO - Using real prefix '/tools/python27' 22:46:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:46:19 INFO - Installing distribute.............................................................................................................................................................................................done. 22:46:21 INFO - Installing pip.................done. 22:46:21 INFO - Return code: 0 22:46:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:46:21 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')]} 22:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:21 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')]} 22:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:21 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:21 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 22:46:21 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 22:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:21 INFO - 'HOME': '/Users/cltbld', 22:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:21 INFO - 'LOGNAME': 'cltbld', 22:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:21 INFO - 'MOZ_NO_REMOTE': '1', 22:46:21 INFO - 'NO_EM_RESTART': '1', 22:46:21 INFO - 'PAGER': '/bin/cat', 22:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:21 INFO - 'PWD': '/builds/slave/test', 22:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:21 INFO - 'SHELL': '/bin/bash', 22:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:21 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:21 INFO - 'USER': 'cltbld', 22:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:21 INFO - 'XPC_FLAGS': '0x0', 22:46:21 INFO - 'XPC_SERVICE_NAME': '0', 22:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:22 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 22:46:22 INFO - Cleaning up... 22:46:22 INFO - Return code: 0 22:46:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:46:22 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')]} 22:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:22 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')]} 22:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:22 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:22 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 22:46:22 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 22:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:22 INFO - 'HOME': '/Users/cltbld', 22:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:22 INFO - 'LOGNAME': 'cltbld', 22:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:22 INFO - 'MOZ_NO_REMOTE': '1', 22:46:22 INFO - 'NO_EM_RESTART': '1', 22:46:22 INFO - 'PAGER': '/bin/cat', 22:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:22 INFO - 'PWD': '/builds/slave/test', 22:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:22 INFO - 'SHELL': '/bin/bash', 22:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:22 INFO - 'USER': 'cltbld', 22:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:22 INFO - 'XPC_FLAGS': '0x0', 22:46:22 INFO - 'XPC_SERVICE_NAME': '0', 22:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:22 INFO - Downloading/unpacking psutil>=3.1.1 22:46:22 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 22:46:22 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 22:46:22 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 22:46:22 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 22:46:22 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 22:46:22 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 22:46:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:46:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:46:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:46:25 INFO - Installing collected packages: psutil 22:46:25 INFO - Running setup.py install for psutil 22:46:25 INFO - building 'psutil._psutil_osx' extension 22:46:25 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 22:46:25 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 22:46:26 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 22:46:26 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 22:46:26 INFO - building 'psutil._psutil_posix' extension 22:46:26 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 22:46:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:46:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:46:26 INFO - ^ 22:46:26 INFO - 1 warning generated. 22:46:26 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 22:46:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:46:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:46:26 INFO - Successfully installed psutil 22:46:26 INFO - Cleaning up... 22:46:26 INFO - Return code: 0 22:46:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:46:26 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')]} 22:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:26 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')]} 22:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:26 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:26 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 22:46:26 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 22:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:26 INFO - 'HOME': '/Users/cltbld', 22:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:26 INFO - 'LOGNAME': 'cltbld', 22:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:26 INFO - 'MOZ_NO_REMOTE': '1', 22:46:26 INFO - 'NO_EM_RESTART': '1', 22:46:26 INFO - 'PAGER': '/bin/cat', 22:46:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:26 INFO - 'PWD': '/builds/slave/test', 22:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:26 INFO - 'SHELL': '/bin/bash', 22:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:26 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:26 INFO - 'USER': 'cltbld', 22:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:26 INFO - 'XPC_FLAGS': '0x0', 22:46:26 INFO - 'XPC_SERVICE_NAME': '0', 22:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:46:27 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 22:46:27 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 22:46:27 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 22:46:27 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 22:46:27 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 22:46:27 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 22:46:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:46:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:46:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:46:30 INFO - Installing collected packages: mozsystemmonitor 22:46:30 INFO - Running setup.py install for mozsystemmonitor 22:46:30 INFO - Successfully installed mozsystemmonitor 22:46:30 INFO - Cleaning up... 22:46:30 INFO - Return code: 0 22:46:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:46: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')]} 22:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46: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')]} 22:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:30 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:30 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 22:46:30 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 22:46:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:30 INFO - 'HOME': '/Users/cltbld', 22:46:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:30 INFO - 'LOGNAME': 'cltbld', 22:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:30 INFO - 'MOZ_NO_REMOTE': '1', 22:46:30 INFO - 'NO_EM_RESTART': '1', 22:46:30 INFO - 'PAGER': '/bin/cat', 22:46:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:30 INFO - 'PWD': '/builds/slave/test', 22:46:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:30 INFO - 'SHELL': '/bin/bash', 22:46:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:30 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:30 INFO - 'USER': 'cltbld', 22:46:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:30 INFO - 'XPC_FLAGS': '0x0', 22:46:30 INFO - 'XPC_SERVICE_NAME': '0', 22:46:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:30 INFO - Downloading/unpacking blobuploader==1.2.4 22:46:30 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 22:46:30 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 22:46:30 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 22:46:30 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 22:46:30 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 22:46:30 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 22:46:33 INFO - Downloading blobuploader-1.2.4.tar.gz 22:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:46:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:46:33 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 22:46:33 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 22:46:33 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 22:46:33 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 22:46:33 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 22:46:33 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 22:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:46:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:46:33 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 22:46:33 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 22:46:33 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 22:46:33 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 22:46:33 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 22:46:33 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 22:46:34 INFO - Downloading docopt-0.6.1.tar.gz 22:46:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:46:34 INFO - Installing collected packages: blobuploader, requests, docopt 22:46:34 INFO - Running setup.py install for blobuploader 22:46:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:46:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:46:34 INFO - Running setup.py install for requests 22:46:34 INFO - Running setup.py install for docopt 22:46:35 INFO - Successfully installed blobuploader requests docopt 22:46:35 INFO - Cleaning up... 22:46:35 INFO - Return code: 0 22:46:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:35 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:35 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 22:46:35 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 22:46:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:35 INFO - 'HOME': '/Users/cltbld', 22:46:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:35 INFO - 'LOGNAME': 'cltbld', 22:46:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:35 INFO - 'MOZ_NO_REMOTE': '1', 22:46:35 INFO - 'NO_EM_RESTART': '1', 22:46:35 INFO - 'PAGER': '/bin/cat', 22:46:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:35 INFO - 'PWD': '/builds/slave/test', 22:46:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:35 INFO - 'SHELL': '/bin/bash', 22:46:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:35 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:35 INFO - 'USER': 'cltbld', 22:46:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:35 INFO - 'XPC_FLAGS': '0x0', 22:46:35 INFO - 'XPC_SERVICE_NAME': '0', 22:46:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:46:35 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-t4vu6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:46:35 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ZggEcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:46:35 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-oYOIUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:46:35 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-tVJ75g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:46:35 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-TRE7kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-rdpSNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-2vsPmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Vg8lqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ECEFF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-_TiSJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-qccuIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Mm_0Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-niFc2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-UH6jAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-b8HnVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:46:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-d1zcA5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:46:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-SXs8FI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:46:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:46:37 INFO - Running setup.py install for manifestparser 22:46:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:46:37 INFO - Running setup.py install for mozcrash 22:46:37 INFO - Running setup.py install for mozdebug 22:46:37 INFO - Running setup.py install for mozdevice 22:46:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:46:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:46:37 INFO - Running setup.py install for mozfile 22:46:38 INFO - Running setup.py install for mozhttpd 22:46:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Running setup.py install for mozinfo 22:46:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Running setup.py install for mozInstall 22:46:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Running setup.py install for mozleak 22:46:38 INFO - Running setup.py install for mozlog 22:46:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Running setup.py install for moznetwork 22:46:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:46:38 INFO - Running setup.py install for mozprocess 22:46:39 INFO - Running setup.py install for mozprofile 22:46:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:46:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:46:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:46:39 INFO - Running setup.py install for mozrunner 22:46:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:46:39 INFO - Running setup.py install for mozscreenshot 22:46:39 INFO - Running setup.py install for moztest 22:46:39 INFO - Running setup.py install for mozversion 22:46:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:46:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:46:39 INFO - Cleaning up... 22:46:39 INFO - Return code: 0 22:46:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:39 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:39 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 22:46:39 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 22:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:39 INFO - 'HOME': '/Users/cltbld', 22:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:39 INFO - 'LOGNAME': 'cltbld', 22:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:39 INFO - 'MOZ_NO_REMOTE': '1', 22:46:39 INFO - 'NO_EM_RESTART': '1', 22:46:39 INFO - 'PAGER': '/bin/cat', 22:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:39 INFO - 'PWD': '/builds/slave/test', 22:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:39 INFO - 'SHELL': '/bin/bash', 22:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:39 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:39 INFO - 'USER': 'cltbld', 22:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:39 INFO - 'XPC_FLAGS': '0x0', 22:46:39 INFO - 'XPC_SERVICE_NAME': '0', 22:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-2db2o4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-A_ryXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-onTg7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-HDPgED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-kjIrdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-3oMWlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-AJOVVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-4s9kp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:46:40 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)) 22:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:46:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-DXLO4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-DIF6bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-0Ruc_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ej98nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-5XISf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-MlkrEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Vgw7X3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-1A5NsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:46:41 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)) 22:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:46:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-qo9pey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:46:41 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)) 22:46:41 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)) 22:46:41 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)) 22:46:41 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)) 22:46:41 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)) 22:46:41 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)) 22:46:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:46:41 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 22:46:41 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 22:46:41 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 22:46:41 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 22:46:41 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 22:46:41 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 22:46:44 INFO - Downloading blessings-1.6.tar.gz 22:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:46:44 INFO - Installing collected packages: blessings 22:46:44 INFO - Running setup.py install for blessings 22:46:45 INFO - Successfully installed blessings 22:46:45 INFO - Cleaning up... 22:46:45 INFO - Return code: 0 22:46:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:46:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:46:45 INFO - Reading from file tmpfile_stdout 22:46:45 INFO - Current package versions: 22:46:45 INFO - blessings == 1.6 22:46:45 INFO - blobuploader == 1.2.4 22:46:45 INFO - docopt == 0.6.1 22:46:45 INFO - manifestparser == 1.1 22:46:45 INFO - mozInstall == 1.12 22:46:45 INFO - mozcrash == 0.17 22:46:45 INFO - mozdebug == 0.1 22:46:45 INFO - mozdevice == 0.48 22:46:45 INFO - mozfile == 1.2 22:46:45 INFO - mozhttpd == 0.7 22:46:45 INFO - mozinfo == 0.9 22:46:45 INFO - mozleak == 0.1 22:46:45 INFO - mozlog == 3.2 22:46:45 INFO - moznetwork == 0.27 22:46:45 INFO - mozprocess == 0.22 22:46:45 INFO - mozprofile == 0.28 22:46:45 INFO - mozrunner == 6.11 22:46:45 INFO - mozscreenshot == 0.1 22:46:45 INFO - mozsystemmonitor == 0.3 22:46:45 INFO - moztest == 0.7 22:46:45 INFO - mozversion == 1.4 22:46:45 INFO - psutil == 3.1.1 22:46:45 INFO - requests == 1.2.3 22:46:45 INFO - wsgiref == 0.1.2 22:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:45 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:45 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 22:46:45 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 22:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:45 INFO - 'HOME': '/Users/cltbld', 22:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:45 INFO - 'LOGNAME': 'cltbld', 22:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:45 INFO - 'MOZ_NO_REMOTE': '1', 22:46:45 INFO - 'NO_EM_RESTART': '1', 22:46:45 INFO - 'PAGER': '/bin/cat', 22:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:45 INFO - 'PWD': '/builds/slave/test', 22:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:45 INFO - 'SHELL': '/bin/bash', 22:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:45 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:45 INFO - 'USER': 'cltbld', 22:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:45 INFO - 'XPC_FLAGS': '0x0', 22:46:45 INFO - 'XPC_SERVICE_NAME': '0', 22:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 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)) 22:46:45 INFO - Cleaning up... 22:46:45 INFO - Return code: 0 22:46:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:45 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 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:45 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 22:46:45 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 22:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:45 INFO - 'HOME': '/Users/cltbld', 22:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:45 INFO - 'LOGNAME': 'cltbld', 22:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:45 INFO - 'MOZ_NO_REMOTE': '1', 22:46:45 INFO - 'NO_EM_RESTART': '1', 22:46:45 INFO - 'PAGER': '/bin/cat', 22:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:45 INFO - 'PWD': '/builds/slave/test', 22:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:45 INFO - 'SHELL': '/bin/bash', 22:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:45 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:45 INFO - 'USER': 'cltbld', 22:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:45 INFO - 'XPC_FLAGS': '0x0', 22:46:45 INFO - 'XPC_SERVICE_NAME': '0', 22:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:45 INFO - Downloading/unpacking jsonschema 22:46:45 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 22:46:45 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 22:46:45 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 22:46:45 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 22:46:45 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 22:46:45 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 22:46:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:46:48 INFO - Downloading/unpacking functools32 (from jsonschema) 22:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:48 INFO - Downloading functools32-3.2.3-2.tar.gz 22:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:46:48 INFO - warning: no files found matching '*.txt' 22:46:48 INFO - no previously-included directories found matching 'build' 22:46:48 INFO - no previously-included directories found matching 'dist' 22:46:48 INFO - no previously-included directories found matching '.git*' 22:46:48 INFO - Installing collected packages: jsonschema, functools32 22:46:48 INFO - Running setup.py install for functools32 22:46:49 INFO - warning: no files found matching '*.txt' 22:46:49 INFO - no previously-included directories found matching 'build' 22:46:49 INFO - no previously-included directories found matching 'dist' 22:46:49 INFO - no previously-included directories found matching '.git*' 22:46:49 INFO - Successfully installed jsonschema functools32 22:46:49 INFO - Cleaning up... 22:46:49 INFO - Return code: 0 22:46:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 22:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:46:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102d6fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d75570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa91575df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, '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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 22:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:46:49 INFO - 'HOME': '/Users/cltbld', 22:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:46:49 INFO - 'LOGNAME': 'cltbld', 22:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:49 INFO - 'MOZ_NO_REMOTE': '1', 22:46:49 INFO - 'NO_EM_RESTART': '1', 22:46:49 INFO - 'PAGER': '/bin/cat', 22:46:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:49 INFO - 'PWD': '/builds/slave/test', 22:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:46:49 INFO - 'SHELL': '/bin/bash', 22:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:46:49 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:46:49 INFO - 'USER': 'cltbld', 22:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:49 INFO - 'XPC_FLAGS': '0x0', 22:46:49 INFO - 'XPC_SERVICE_NAME': '0', 22:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:49 INFO - Downloading/unpacking flake8 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46: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 22:46:51 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:46:51 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:46:51 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 22:46:51 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 22:46:51 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 22:46:51 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 22:46:51 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 22:46:51 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 22:46:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:46:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:52 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:46:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:46:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:46:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:46:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:46:53 INFO - no previously-included directories found matching 'docs/_build' 22:46:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:46:53 INFO - Running setup.py install for pep8 22:46:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:46:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:46:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:46:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:46:53 INFO - no previously-included directories found matching 'docs/_build' 22:46:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:46:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:46:53 INFO - Cleaning up... 22:46:53 INFO - Return code: 0 22:46:53 INFO - Running post-action listener: _resource_record_post_action 22:46:53 INFO - Running post-action listener: _start_resource_monitoring 22:46:53 INFO - Starting resource monitoring. 22:46:53 INFO - ##### 22:46:53 INFO - ##### Finished create-virtualenv step (success) 22:46:53 INFO - ##### 22:46:53 INFO - ##### 22:46:53 INFO - ##### Running install step. 22:46:53 INFO - ##### 22:46:53 INFO - Running pre-action listener: _resource_record_pre_action 22:46:53 INFO - Running main action method: install 22:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:46:53 INFO - Reading from file tmpfile_stdout 22:46:53 INFO - Detecting whether we're running mozinstall >=1.0... 22:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:46:53 INFO - Reading from file tmpfile_stdout 22:46:53 INFO - Output received: 22:46:53 INFO - Usage: mozinstall [options] installer 22:46:53 INFO - Options: 22:46:53 INFO - -h, --help show this help message and exit 22:46:53 INFO - -d DEST, --destination=DEST 22:46:53 INFO - Directory to install application into. [default: 22:46:53 INFO - "/builds/slave/test"] 22:46:53 INFO - --app=APP Application being installed. [default: firefox] 22:46:53 INFO - mkdir: /builds/slave/test/build/application 22:46:53 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'] 22:46:53 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 22:47:27 INFO - Reading from file tmpfile_stdout 22:47:27 INFO - Output received: 22:47:27 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:47:27 INFO - Running post-action listener: _resource_record_post_action 22:47:27 INFO - ##### 22:47:27 INFO - ##### Finished install step (success) 22:47:27 INFO - ##### 22:47:27 INFO - ##### 22:47:27 INFO - ##### Running run-tests step. 22:47:27 INFO - ##### 22:47:27 INFO - Running pre-action listener: _resource_record_pre_action 22:47:27 INFO - Running main action method: run_tests 22:47:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:47:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:47:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:47:27 INFO - 2016-05-30 22:47:27.672 screenresolution[1641:7692] starting screenresolution argv=screenresolution get 22:47:27 INFO - 2016-05-30 22:47:27.675 screenresolution[1641:7692] Display 0: 1600x1200x32@60 22:47:27 INFO - 2016-05-30 22:47:27.680 screenresolution[1642:7694] starting screenresolution argv=screenresolution list 22:47:27 INFO - Available Modes on Display 0 22:47:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:47:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:47:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:47:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:47:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:47:27 INFO - Intel Iris: 22:47:27 INFO - Chipset Model: Intel Iris 22:47:27 INFO - Type: GPU 22:47:27 INFO - Bus: Built-In 22:47:27 INFO - VRAM (Dynamic, Max): 1536 MB 22:47:27 INFO - Vendor: Intel (0x8086) 22:47:27 INFO - Device ID: 0x0a2e 22:47:27 INFO - Revision ID: 0x0009 22:47:27 INFO - Displays: 22:47:27 INFO - Display: 22:47:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:47:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:47:27 INFO - Display Serial Number: 1600x1200 60 22:47:27 INFO - Main Display: Yes 22:47:27 INFO - Mirror: Off 22:47:27 INFO - Online: Yes 22:47:27 INFO - Rotation: Supported 22:47:27 INFO - Return code: 0 22:47:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:47:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:47:27 INFO - Python 2.7.3 22:47:27 INFO - Return code: 0 22:47:27 INFO - grabbing minidump binary from tooltool 22:47:27 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')]} 22:47:27 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 0x102c13d30>, '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 0x102a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c0ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:47:27 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 22:47:27 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 22:47:27 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 22:47:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:47:28 INFO - Return code: 0 22:47:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:47:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:47:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:47:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:47:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:47:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:47:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:47:29 INFO - Return code: 0 22:47:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:47:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0358 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:47:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3KMZ2Yawip/Render', 22:47:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:47:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:47:29 INFO - 'HOME': '/Users/cltbld', 22:47:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:47:29 INFO - 'LOGNAME': 'cltbld', 22:47:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:47:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:29 INFO - 'MOZ_NO_REMOTE': '1', 22:47:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:47:29 INFO - 'NO_EM_RESTART': '1', 22:47:29 INFO - 'PAGER': '/bin/cat', 22:47:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:29 INFO - 'PWD': '/builds/slave/test', 22:47:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:47:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:47:29 INFO - 'SHELL': '/bin/bash', 22:47:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iB1nxoxpGC/Listeners', 22:47:29 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 22:47:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:47:29 INFO - 'USER': 'cltbld', 22:47:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:47:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:29 INFO - 'XPC_FLAGS': '0x0', 22:47:29 INFO - 'XPC_SERVICE_NAME': '0', 22:47:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:47:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:47:29 INFO - mozversion application_buildid: 20160530205547 22:47:29 INFO - mozversion application_changeset: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 22:47:29 INFO - mozversion application_display_name: Nightly 22:47:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:47:29 INFO - mozversion application_name: Firefox 22:47:29 INFO - mozversion application_remotingname: firefox 22:47:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:47:29 INFO - mozversion application_vendor: Mozilla 22:47:29 INFO - mozversion application_version: 49.0a1 22:47:29 INFO - mozversion platform_buildid: 20160530205547 22:47:29 INFO - mozversion platform_changeset: 42fab251fe111d5f891c9bde0ee1fb6f7f946a50 22:47:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:47:29 INFO - mozversion platform_version: 49.0a1 22:47:29 INFO - using testdate: 1464673649 22:47:29 INFO - actual date: 1464673649 22:47:29 INFO - starting webserver on 'localhost:49278' 22:47:29 INFO - SUITE-START | Running 2 tests 22:47:29 INFO - TEST-START | dromaeo_css 22:47:29 INFO - operating with platform_type : mac_ 22:47:29 INFO - Initialising browser for dromaeo_css test... 22:47:29 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpbCIHTh/profile http://localhost:49278/getInfo.html) 22:47:32 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 22:47:32 INFO - PROCESS | 1660 | colorDepth:24 22:47:32 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 22:47:32 INFO - PROCESS | 1660 | __metrics 22:47:32 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 22:47:33 INFO - TEST-INFO | 1660: exit 0 22:47:33 INFO - Browser initialized. 22:47:33 INFO - Running cycle 1/1 for dromaeo_css test... 22:47:33 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpbCIHTh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:47:45 INFO - PROCESS | 1663 | RSS: Main: 252272640 22:47:45 INFO - PROCESS | 1663 | 22:48:34 INFO - PROCESS | 1663 | 1464673714871 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] 22:50:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 22:50:46 INFO - PROCESS | 1663 | RSS: Main: 266633216 22:50:46 INFO - PROCESS | 1663 | 22:53:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 22:53:49 INFO - PROCESS | 1663 | RSS: Main: 277950464 22:53:49 INFO - PROCESS | 1663 | 22:56:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 22:56:52 INFO - PROCESS | 1663 | RSS: Main: 280109056 22:56:52 INFO - PROCESS | 1663 | 22:59:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 22:59:54 INFO - PROCESS | 1663 | RSS: Main: 281399296 22:59:54 INFO - PROCESS | 1663 | 23:02:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 23:02:57 INFO - PROCESS | 1663 | RSS: Main: 285179904 23:02:57 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 23:06:00 INFO - PROCESS | 1663 | RSS: Main: 285450240 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | __start_tp_report 23:06:00 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 23:06:00 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 23:06:00 INFO - PROCESS | 1663 | |i|pagename|runs| 23:06:00 INFO - PROCESS | 1663 | |0;dojo.html;3441.4665726812354;3472.808995505247;3487.6686714762104;3509.8947031589046;3511.0169152637263;5703.230063941367;5814.302283725953;5821.359650438449;5824.621399609023;5850.912236316453;4572.611328037121;5767.567432442563;5804.738786754596;5813.128030795372;5820.592558520905;5170.457102004292;5255.553277971358;5302.2311764794085;5312;5366.329208848894;222.85291358039078;5320.962412329597;5328.254044433782;5331.973340133294;5398.649087809299;5250.081235783741;5250.264962905197;5252.36971563411;5284.735763211863;5301.363836339645;5191.922121168148;5310;5326.9467305327225;5342.332208473941;5357.624966252325;5022.472640372781;5029.471905449912;5071.923921141186;5076.492350764931;5094.541491265803;5488.423715509894;5518.696471694056;5529.695866727328;5532.087205611069;5638.126090455986;6015.789447369365;6079.148919151322;6110.358412366726;6116.938830611726;6128.172696685935;6262.937370626281;6275.435210831001;6288.465480434123;6334.936650633481;6336.619802811846;5912.822615321547;5920.141579470953;5942.6731529766275;5951.845521873414;6008.158857759873;6214.8135555933395;6248.875022499616;6271.264733200557;6282.089097080898;6311.810645680545;6262.874742505124;6271.216097987752;6295.213098362705;6301.180846489927;6301.40136687014;6263.906041409383;6270.435660790551;6286.748530058838;6301.4328710416285;6317.905231421532;4627.282771170474;5357.678539287655;6124.5100391968535;6130.908036379458;6273.12176295319;6102.0651310563235;6207.317195108534;6269.65516896566;6283.7800676977;6313.337099604567;4992.90014199714;5056.393232812013;5066.316047333563;5066.569341606005;5095.184770436712;4457.375967364572;4458.375827384169;4482.574155455227;4510.143072816154;4572.634189264851;5926.318473375531;5961.970190149022;5966.820995370146;5997.760089596367;6027.619090459244;5434.864128396822;6162.829062478115;6288.496920246369;6316;6320.209973753281;5711.485966262973;5805.245318108703;5845.912311315334;5909.084091965752;5910.615809972338;5511.063119269734;5530.668159910338;5535.391106978229;5537.1140617501815;5546.140348246028;6417.326180751047;6420.7431702731365;6442.098106265126;6467.126937863422;6479.352064793483;6217.875642487215;6247.281562620356;6251.312355640966;6286;6332.968335158294;6364.236291645032;6375.139356186855;6396.776112836028;6398.392152745481;6430.517711171775;6138.57030007913;6268.874622507433;6269.122322874892;6272;6284.968575157095;6179.81460556184;6270.874582508415;6306.117143599808;6323.620582765049;6329.113924050729;6198.535109866868;6200.534959878118;6222.968885155727;6239.875202495834;6243.843903902439;6051.364606716232;6098.237720284965;6129.325774164928;6202.193714817045;6206.658633775186;5185.0148471790235;5690.829275121753;5999.040153575407;6049.879002419839;6059.575829691879;6176.197094377702;6184.134221209124;6212.502999760099;6261;6272.059191121369;5921.319048309328;6039.335673076046;6043.425874541911;6068.4538391544975;6113.724882380393 23:06:00 INFO - PROCESS | 1663 | |1;ext.html;508.88804463018147;517.888653939403;529.7007190937118;539.1454544546893;545.5471958274632;38284.617153828454;40597.7970110149;40645.390319145234;40660.39009414861;40712.18575628486;42286.78856605721;42885;42896.78551607238;42976.35535466971;43130.784346078304;9363.85954210684;9369.625214991393;9568.282378821581;9573.282003849705;9596.520173991272;9334.766630834249;9435.198008169315;9446.66936657217;9456.432614043146;9482.952585237099;9441.103095205945;9460.810783784354;9469.337146399756;9470.384425012386;9481.288903332243;9432.622695092188;9468.242540596735;9475.147236748691;9487;9509.191718703849;9358.204552613037;9486;9513.66702165421;9522.619095236183;9523.857142142873;38699.22601547953;38735.418968715494;39082.804585977174;39115.60884391148;39381.40927886084;3060.8066948280775;3364.5121457388786;3473.218525831693;3498.8250587470525;3569.4824250483534;29296.70703292961;29915.700842991508;30633.927812526454;30810.84594577035;31150.688493115005;2604.5311843868285;3064.2045199263302;3077.569140320357;3092.932648897259;3096.4426403247408;379686;462146;465549;465814;468050.99999999657;33645.83177084099;33674.663253367646;33780.15549611279;33883.32233355319;33902.135382554196;34293.82853085719;34659.82670086633;34756.65243347585;35230.295394091976;35814.820925895205;16617.50147495577;16720.74918876218;16833;16834.494965151065;16871.32514699423;12785.424655890507;12858.807117893239;12886.29125398112;12892;12942.547010854574;16491.58771030734;17069.40257090996;18970.051497425073;19317.903410482857;19541;7960;8058.6959346668755;8109;8137.552434616153;8147.918520814835;1015.827309357411;1051.6687243518268;1072;1075.0112292259257;1084.4415126209851;29182;30057.94797182132;30387.848060759556;30598;30939.69060309413;1094.441834664311;1100.49927283086;1102.0742576236;1139.32779660001;1243.664210663116;11983.880161198276;15172.61256963392;15586.376544938075;15821.288042038135;16022.519324420286;166728;175521.4895702079;191314;192525.07474925072;192565;58699;58701;59453.702731486934;59475.40524594699;59719.40280597138;1422.4807945100172;1433.175923843794;1439.0574173916102;1448.9565313040837;1453.3605213705935;30138.54792178163;31172.908948186705;31428.528572071893;31986;32403.51394729128;57702.7114864423;57779.71110144423;58664.413355865894;58709.41290587039;58803.70598146982;585258;588508;588619;594085;594809;27073.45853082888;27858.746356414416;27892.442151156458;44631;45167.548324516334;47815.521844781106;48657;49531.25703114384;49543.50456495533;49785.50214497808;38585.8424247273;40205.597944020185;40243.39634905537;40969;40997.97505062397;38580.228395432496;38817.2236555273;39305;39632.60367396405;39714.801425992686;38278;38496.2300753989;38695.41956870705;39144.41283380695;39150.80424597859;25120.74879251184;32754;34843.78046768351;35225;35703;21430.57138857183;25446.74553254444;25633.487330252916;25704.742952570236;26110.738892610832 23:06:00 INFO - PROCESS | 1663 | |2;jquery.html;340.926700759337;358.1386764830582;379.62417206965114;385.10078965615287;399.2812936713916;14546.490872819453;20033.19867205312;20307.08618112185;21162.047707853144;21230.46923826903;11559.422028898542;12206.694832629166;20062.89968550159;20514.897425512852;20543.589128217427;5062.544371006617;5109.3613298337705;5138.691678499284;5187.922181167267;5217.060929032773;5352.009878172531;5667.4615911488345;6051.364606716297;6060.666663333515;6096.237970253718;6070.72681729321;6081.148639190517;6133.325334213233;6837.829054273658;6858;6232.501399887998;6829.7609583664835;6856.280090590466;6857.691403886812;6873.862522749542;5973.253343332083;5989.281286245635;6010.429009244115;6014.8195554133445;6019.157317975487;24654.630180547305;24761.009559617774;25255.98976040956;25404.364890877692;25737.227883163534;2111.4193596761015;2143.046344376753;2307.8730669813153;2357.728861180969;2395.4849707312837;10207.6427325044;10263.332883362558;10343.120834729058;10410.219233557438;10513.579456821719;1860.0978525415162;1894.1760334254643;1896.3268039845793;1983.2959299448776;2002.879827210372;127913;132115;133300;133832;134002.65997340097;11589.594364197214;11858;11859.58491452795;11882.524699011943;12006.819897701542;14801.555953321418;14919.40322387114;14954.252287385587;14961.40154393812;14962.476313328982;7963.8407231855035;7980.920190798018;8200.384971127189;8510.36172287081;8742.213200811957;7807;7894.526328420313;8475.957620211859;8546.658133674708;8589.785255368666;4474.6420286377015;4612.562589856711;4618.699784513996;4645.094206629742;4649.372334734835;1925.7977912319284;1974.940751777449;1977.812107849752;1988.7315212446135;2024.9291274805312;846.6147902704255;879.9453276599085;895.83427065993;924.6301479408156;953.2326477185932;20208.19167233294;20946.476338091365;21074.946252687612;21319.89340053321;21331.68002479964;865.8008658008567;915.8992510823804;928.9303302252358;989.3371441134457;997.0777031246068;9548.854137503544;10931.56273749057;11026.889731102747;11217.270877392943;11247.987681108576;620036;621638;623667;632694;636250;89080;119672;133543;137896;145203.27398363152;856.7558245899888;1009.6466236817055;1058.317385286486;1096.555894862582;1103.2938919091787;14629.92685036568;15459.381624735335;17761.02314372722;18523.0738463079;19064.523386915436;55213.79144834162;56273.155902662314;57508.137377938525;58271.251862444195;59207.703961479914;84114;84322.15677843142;84654.56718201606;85627.57186214029;85806;7955.761327160194;7990.161033091442;8112.148224436349;8141.104478507478;8308.5430301334;11792.351420671945;12383.504659813507;12492.687682808002;12680.492780289054;12714.61856144317;9772.729545159369;10187;10412.32319899204;10767.246292759432;11200.38397888124;10692.037716605542;10827.133829293794;10836.099004358583;10863.022327990519;11069.446527673423;11119.332840029625;11331.603393881174;11403;11434.656960291204;11445.599404020819;8053.154418786177;8104.432689711663;8150.388720845842;8261.545614990993;8290.502569845828;9497.810043799223;11141.665750027512;11263.26788758728;11272.605458808903;11293.71765705864 23:06:00 INFO - PROCESS | 1663 | |3;mootools.html;1315.5329857900444;1471.1393834606752;1616.2242123780586;1785.9017754023523;1827.6710192165406;7612.77161685149;7976.920230797705;8571.142885711426;8985.640574377034;9005.819883602333;8693.69572064978;8746.387752857303;8838.160374764404;8886.244669203126;8897.466152030882;2359.622460406331;2386.3556839653284;2391.390195500145;2395.1257790906366;2395.317334559651;2377.7978871795917;2385.2486466762916;2419.129113519132;2424.963625545618;2428.0651948999584;2363.385519764865;2368.656544801003;2375.809935205188;2378.9413710898657;2399.160293897131;2350.9059637614473;2365.38499990003;2373.738888722239;2384.892679829412;2390.1395497620842;2327.8254130940245;2336.2523992322463;2348.178137651825;2364.9054037838464;2371.063429945162;8769.517676527788;8797.824043519095;8806.20744133031;8900.688475903313;8932.598033088527;5300.496452836975;5427.755750991215;5459.535939445152;5463.562914966793;5468.043092458844;3073.9097413758636;3510.8244587770764;3574.4995700601935;3626.873059442933;3655.94516082259;991.6430085169334;994.7363948553639;1001.394156535293;1067.6156583629886;1069.3744159666605;11488.942555287254;11600.6519804406;11624.535018599247;11630.953214210762;11698.06415486759;4806.639502037291;4811.422629284508;4859.829905953346;4878.658493905393;4933.827316043921;8293.668253269921;9930.652427165014;9956.601735930482;9962.153216976492;9982.401055936667;5296.708681022581;5312.282841816305;5373.7850485980125;5388.137897936312;5407.134858422633;4435.467743870756;4771.117343291502;4944.505549445027;4949.183384741477;4955.281484184773;3979.6816254699556;4049.8987525311513;4065.5121385433936;4107.014316564063;4174.874753757332;1120.3642681684669;1296.5915736542959;1314.7173357728134;1315.7171208190284;1317.5915466205506;933.4446004627235;942.3686130292721;946.8721722567503;953.8378475659155;985.4235272365746;5903.114532820111;5974.163617093611;6053.7578496860515;6124.816255512342;6136.631802091889;413.65768082571645;433.4646711311528;434.73698412460004;443.85352833564843;448.2491826191182;3759.830807613664;4112.91774164515;4124.52567954683;4138.606832350922;4162.937555936664;10311.742206444898;10671.626493072705;10691.62579309736;10733.78532429347;10742.946285268523;10262.230332725076;10592.57629694819;10731.409772462433;10750.73123171927;10800;8093.27160555541;8144;8163.877541836877;8188;8267.503949763033;5340.973295133655;5521.917171242515;5894.410558944071;5982.282126144891;6085.087236914321;9590.760230994281;9894.752631184278;9969.003099689973;10106.595736170471;10118.190544756255;10120.392776433437;10321.380717156995;10321.432321222404;10326.586936522455;10389.324693894872;6097.5731698780655;7033.683484243119;7584.469087163846;7705.152433232228;7785.260400261966;8530;8543.829123417621;8546.78633034179;8605.440646357967;8614.483131012159;7530.698772049057;7608.31525162738;7627.54234745917;7716.92283077162;7800.492967957065;7401.259874012771;7510.887336689845;7518.962405187939;7551.622418878924;7647.19704431049;7064.293570642894;7071.893921591078;7133.108361454818;7156.606386648784;7171.49799514029;4565.338025986147;4927.753612319441;4930.087933732271;5130.384353877558;5131.87170320745;5089.033083714083;5103.285540024473;5131.4868513148385;5170.767315470888;5243.23973023917 23:06:00 INFO - PROCESS | 1663 | |4;prototype.html;272.43877612119036;312.66857131440673;319.6340190481898;325.12379138222485;325.263278673804;4521.660875434339;5518.530924871391;5596.888062238752;5673.23411339469;5692.886142277157;4678.298255261712;5564.527015203713;5576.972115139429;5584.162375643656;5602.142907117379;3986.481757371545;4130.772807495587;4185.890738954179;4210.3684447332835;4219.578042195786;3337.9499307510405;4039.313316736162;4129.834806607732;4165.729227600212;4220.788960551976;3547.4856145858835;3549.7515173937836;3858.9228215435674;3964.603539646041;4264.189803937242;3913.882583522499;4216.2199993001395;4254.446921900164;4260.97869510652;4271.992163683095;3356.3622911646708;3560.0743806610208;3663.4871118043493;3683.3977219909425;3690.6678398943955;5389.51494365509;5394.298741163663;5418.403975562725;5418.810341638064;5514.476124768141;4342.34864770287;4517.638588912879;4852.811061290006;4874.634402419797;4948.67833590819;5213.504717051874;5844.123381492767;5869.442402971754;5871.706414679292;5921.713449035783;4071.6131967463043;4076.2255171517318;4078.4697989261504;4085.407615895708;4087.9386809197886;5591.46881046304;5841.211436456067;6160.398296442912;6199.473044791198;6244.1570387997945;3467.6185619581825;3533.311004354178;4118.341065429517;4133.152703695795;4145.274576949082;3407.4037043517287;3746.456763769238;4167.874963751093;4178.937315940263;4222.1766755483;4515.322701594721;4818.614510839125;4831.51684831514;4902.779374928136;4915.729634870117;5907.497862681635;5912.733926973297;5928.822135335947;5929.851753706105;5937.910931336033;1308.2542950518305;1421.7369786589513;1449.311577000916;1452.3609611770787;1459.9635009124856;864.900536438305;906.4334790755778;906.7415786500816;917.5412293853074;966.8163049020663;4774.331593576832;5258.71077090756;5432.29380180574;5458.925608268833;5471.835844924659;4659;4727.409073865767;5403.432639572867;5969.552283578749;6013.067974463965;4622.520793346097;4931;5060.1903695408555;5254.632175747661;5313.256144139824;2736.7947403944786;2899.405621847516;3131.9507964831582;3149.5748074009716;3189.28241145745;4938.789996450821;5854.443827836434;5895.698408891035;5898;6167.938320616737;5635.5773317001385;5644.6613203207935;6017.308009578867;6119.265688117454;6148.631082135086;6045.879082418415;6213.378662133841;6273.811785646438;6289.96855015722;6323.620582765049;5027.271045698426;5381.408045114956;6006.489448396936;6012.368701286477;6014.6391216527145;5271.71005594696;5735.537437953295;5881.9705901470215;5931.436513531207;5960.165576819162;5979.2824861015215;6213.782517611862;6218.347073557211;6251.4061164189325;6262.906056409066;4078.286299897565;4089.241626100805;4110.157417729418;4112;4120.484939382577;5868.3251426086545;5929.881402371842;5974.880502389841;6010.248718910137;6037.607555508878;4776.017952549653;5424.511793938565;5790.94209057904;5813.447722466359;5815.82552523425;5196.844094677166;5286.704757334323;5466.234727138124;5714.5999780015;5745.683987380734;5525.226468294361;5618.775248989995;5650.491455768836;5657.603967722384;5709.372828743788;4825.5898248649255;4833.89725469422;5861.515940305584;5869.501092407193;5890.646561206341;4708.835190768444;5198.960207958499;5713.600167958876;5720.713964301852;5775.364709881826 23:06:00 INFO - PROCESS | 1663 | |5;yui.html;739.2201227704772;741.3661319571765;764.5871229536051;779.8050487378156;782.9217078292171;16066.357345706172;16459;16761.413350532734;16827.074510901897;16865.494035178934;15846.841531584681;15930.52208433746;15936.920315398407;15974;16016.519504414857;717.1859938969255;875.8642410426394;897.4929165021774;913.369774855351;923.3767207135157;905.248643625789;911.3438324406417;911.389369122686;914.1407077347305;916.3356566489308;952.6046690623417;956.8803899512561;958.420155805738;959.1799011844877;963.5615794813343;914.2594498456239;916.422653728147;949.5062567464948;955.016333176829;955.9904400956005;695.0339028749991;697.5012865800926;709.7835160276114;710.8400609862791;711.6584039660992;14542.927285363612;14592.416303347853;14623.04950178235;14646.048006879626;14677.48628797987;13274.734505309936;13416.195028298236;13458.461661533527;13485.662858428617;13591.660208494768;9422.717318480456;11374.658760237206;11385.032272256869;11465;11828.935395814418;3447.931041379158;3537.9739875436094;3718.535183102112;3723.1809002019513;3887.7667339959694;63311;81398.59300703518;81866;82070;82445;9337.206337461392;10373.377597344184;10421.791564168674;10428.009339112772;10671.626493072705;17468.47594572165;19373.51566210856;20036;20112.39662810118;20119.798802012087;6640.435562977104;7862;7868.134505204423;7922.286994170552;8123.18768123183;4499.752513611717;4518.81924723014;4525.434320709911;4536.931946020813;4630.791614377361;3339.1652086978256;3363.428217203081;3393.0499460151195;3456.9827150864085;3466.6013408458352;599.868029033612;607.5048835199299;627.0813258576208;655.6852710698892;658.0819756439739;556.7340922445608;626.5144513002459;642.3191417097797;666.6266890541312;693.4625665109479;9067.183953444222;9090.13643703847;9127.406718563272;9143.954280228556;9316.44101353921;663.5156335874839;663.6880666086931;664.0769330630427;672.0658284983876;675.9087523184307;3756.6055564165367;4162.209180255742;4291.313389857608;4308.332208507686;4460.955390446119;95020.57469137969;96967.0303296958;97965.51017244869;98182;98918;39831.00422489461;84098.57950710329;84171.5791421039;84792;85463.14536854676;71510.56978860497;72450.63774681093;72488.63755681293;72731.27268727245;72819.63590182121;7140.07179066718;8570.78573035679;8602.870956935525;8679.047214376715;8805.735827925173;46389.07221855612;47023.52976470192;47218.81952951204;47486.287705685136;48228.27657585209;61433;65038.67480662566;65443.38226470888;65641.68716625737;65787;11972.521099155936;12122.575709850466;12257.816132758195;12287.385630718607;12434.13804963869;13601.659958501117;13896.235707035805;14068.366923488264;14076.155430674193;14604.415823366946;12577.811332829831;12583.87416125827;12700.110992230824;12778.552750653682;12842.229466232056;12666.936665316614;12887.613371598867;12904.862579281184;12945.805812913002;13382;12022;12214.938925305316;12355.62933111972;12424.316662583284;12606.55877044299;10796.460176991277;10928.398938058483;11009.064229540258;11076.889231107909;11336.886631133582;10398.64122289945;10852.565897364017;10861.131109511061;10877.556509698075;10879.782404352027 23:06:00 INFO - PROCESS | 1663 | __end_tp_report 23:06:00 INFO - PROCESS | 1663 | __start_cc_report 23:06:00 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,416 23:06:00 INFO - PROCESS | 1663 | __end_cc_report 23:06:00 INFO - PROCESS | 1663 | __startTimestamp1464674760918__endTimestamp 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | ------- Summary: start ------- 23:06:00 INFO - PROCESS | 1663 | Number of tests: 6 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5751.00 Median:6027.62 stddev:774.94 (12.9%) stddev-sans-first:755.84 23:06:00 INFO - PROCESS | 1663 | Values: 3441.5 3472.8 3487.7 3509.9 3511.0 5703.2 5814.3 5821.4 5824.6 5850.9 4572.6 5767.6 5804.7 5813.1 5820.6 5170.5 5255.6 5302.2 5312.0 5366.3 222.9 5321.0 5328.3 5332.0 5398.6 5250.1 5250.3 5252.4 5284.7 5301.4 5191.9 5310.0 5326.9 5342.3 5357.6 5022.5 5029.5 5071.9 5076.5 5094.5 5488.4 5518.7 5529.7 5532.1 5638.1 6015.8 6079.1 6110.4 6116.9 6128.2 6262.9 6275.4 6288.5 6334.9 6336.6 5912.8 5920.1 5942.7 5951.8 6008.2 6214.8 6248.9 6271.3 6282.1 6311.8 6262.9 6271.2 6295.2 6301.2 6301.4 6263.9 6270.4 6286.7 6301.4 6317.9 4627.3 5357.7 6124.5 6130.9 6273.1 6102.1 6207.3 6269.7 6283.8 6313.3 4992.9 5056.4 5066.3 5066.6 5095.2 4457.4 4458.4 4482.6 4510.1 4572.6 5926.3 5962.0 5966.8 5997.8 6027.6 5434.9 6162.8 6288.5 6316.0 6320.2 5711.5 5805.2 5845.9 5909.1 5910.6 5511.1 5530.7 5535.4 5537.1 5546.1 6417.3 6420.7 6442.1 6467.1 6479.4 6217.9 6247.3 6251.3 6286.0 6333.0 6364.2 6375.1 6396.8 6398.4 6430.5 6138.6 6268.9 6269.1 6272.0 6285.0 6179.8 6270.9 6306.1 6323.6 6329.1 6198.5 6200.5 6223.0 6239.9 6243.8 6051.4 6098.2 6129.3 6202.2 6206.7 5185.0 5690.8 5999.0 6049.9 6059.6 6176.2 6184.1 6212.5 6261.0 6272.1 5921.3 6039.3 6043.4 6068.5 6113.7 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:55853.03 Median:38390.42 stddev:118267.12 (308.1%) stddev-sans-first:118525.90 23:06:00 INFO - PROCESS | 1663 | Values: 508.9 517.9 529.7 539.1 545.5 38284.6 40597.8 40645.4 40660.4 40712.2 42286.8 42885.0 42896.8 42976.4 43130.8 9363.9 9369.6 9568.3 9573.3 9596.5 9334.8 9435.2 9446.7 9456.4 9483.0 9441.1 9460.8 9469.3 9470.4 9481.3 9432.6 9468.2 9475.1 9487.0 9509.2 9358.2 9486.0 9513.7 9522.6 9523.9 38699.2 38735.4 39082.8 39115.6 39381.4 3060.8 3364.5 3473.2 3498.8 3569.5 29296.7 29915.7 30633.9 30810.8 31150.7 2604.5 3064.2 3077.6 3092.9 3096.4 379686.0 462146.0 465549.0 465814.0 468051.0 33645.8 33674.7 33780.2 33883.3 33902.1 34293.8 34659.8 34756.7 35230.3 35814.8 16617.5 16720.7 16833.0 16834.5 16871.3 12785.4 12858.8 12886.3 12892.0 12942.5 16491.6 17069.4 18970.1 19317.9 19541.0 7960.0 8058.7 8109.0 8137.6 8147.9 1015.8 1051.7 1072.0 1075.0 1084.4 29182.0 30057.9 30387.8 30598.0 30939.7 1094.4 1100.5 1102.1 1139.3 1243.7 11983.9 15172.6 15586.4 15821.3 16022.5 166728.0 175521.5 191314.0 192525.1 192565.0 58699.0 58701.0 59453.7 59475.4 59719.4 1422.5 1433.2 1439.1 1449.0 1453.4 30138.5 31172.9 31428.5 31986.0 32403.5 57702.7 57779.7 58664.4 58709.4 58803.7 585258.0 588508.0 588619.0 594085.0 594809.0 27073.5 27858.7 27892.4 44631.0 45167.5 47815.5 48657.0 49531.3 49543.5 49785.5 38585.8 40205.6 40243.4 40969.0 40998.0 38580.2 38817.2 39305.0 39632.6 39714.8 38278.0 38496.2 38695.4 39144.4 39150.8 25120.7 32754.0 34843.8 35225.0 35703.0 21430.6 25446.7 25633.5 25704.7 26110.7 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:36301.28 Median:24707.82 stddev:104819.37 (424.2%) stddev-sans-first:105078.64 23:06:00 INFO - PROCESS | 1663 | Values: 340.9 358.1 379.6 385.1 399.3 14546.5 20033.2 20307.1 21162.0 21230.5 11559.4 12206.7 20062.9 20514.9 20543.6 5062.5 5109.4 5138.7 5187.9 5217.1 5352.0 5667.5 6051.4 6060.7 6096.2 6070.7 6081.1 6133.3 6837.8 6858.0 6232.5 6829.8 6856.3 6857.7 6873.9 5973.3 5989.3 6010.4 6014.8 6019.2 24654.6 24761.0 25256.0 25404.4 25737.2 2111.4 2143.0 2307.9 2357.7 2395.5 10207.6 10263.3 10343.1 10410.2 10513.6 1860.1 1894.2 1896.3 1983.3 2002.9 127913.0 132115.0 133300.0 133832.0 134002.7 11589.6 11858.0 11859.6 11882.5 12006.8 14801.6 14919.4 14954.3 14961.4 14962.5 7963.8 7980.9 8200.4 8510.4 8742.2 7807.0 7894.5 8476.0 8546.7 8589.8 4474.6 4612.6 4618.7 4645.1 4649.4 1925.8 1974.9 1977.8 1988.7 2024.9 846.6 879.9 895.8 924.6 953.2 20208.2 20946.5 21074.9 21319.9 21331.7 865.8 915.9 928.9 989.3 997.1 9548.9 10931.6 11026.9 11217.3 11248.0 620036.0 621638.0 623667.0 632694.0 636250.0 89080.0 119672.0 133543.0 137896.0 145203.3 856.8 1009.6 1058.3 1096.6 1103.3 14629.9 15459.4 17761.0 18523.1 19064.5 55213.8 56273.2 57508.1 58271.3 59207.7 84114.0 84322.2 84654.6 85627.6 85806.0 7955.8 7990.2 8112.1 8141.1 8308.5 11792.4 12383.5 12492.7 12680.5 12714.6 9772.7 10187.0 10412.3 10767.2 11200.4 10692.0 10827.1 10836.1 10863.0 11069.4 11119.3 11331.6 11403.0 11434.7 11445.6 8053.2 8104.4 8150.4 8261.5 8290.5 9497.8 11141.7 11263.3 11272.6 11293.7 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5728.65 Median:4946.84 stddev:3210.56 (64.9%) stddev-sans-first:3202.43 23:06:00 INFO - PROCESS | 1663 | Values: 1315.5 1471.1 1616.2 1785.9 1827.7 7612.8 7976.9 8571.1 8985.6 9005.8 8693.7 8746.4 8838.2 8886.2 8897.5 2359.6 2386.4 2391.4 2395.1 2395.3 2377.8 2385.2 2419.1 2425.0 2428.1 2363.4 2368.7 2375.8 2378.9 2399.2 2350.9 2365.4 2373.7 2384.9 2390.1 2327.8 2336.3 2348.2 2364.9 2371.1 8769.5 8797.8 8806.2 8900.7 8932.6 5300.5 5427.8 5459.5 5463.6 5468.0 3073.9 3510.8 3574.5 3626.9 3655.9 991.6 994.7 1001.4 1067.6 1069.4 11488.9 11600.7 11624.5 11631.0 11698.1 4806.6 4811.4 4859.8 4878.7 4933.8 8293.7 9930.7 9956.6 9962.2 9982.4 5296.7 5312.3 5373.8 5388.1 5407.1 4435.5 4771.1 4944.5 4949.2 4955.3 3979.7 4049.9 4065.5 4107.0 4174.9 1120.4 1296.6 1314.7 1315.7 1317.6 933.4 942.4 946.9 953.8 985.4 5903.1 5974.2 6053.8 6124.8 6136.6 413.7 433.5 434.7 443.9 448.2 3759.8 4112.9 4124.5 4138.6 4162.9 10311.7 10671.6 10691.6 10733.8 10742.9 10262.2 10592.6 10731.4 10750.7 10800.0 8093.3 8144.0 8163.9 8188.0 8267.5 5341.0 5521.9 5894.4 5982.3 6085.1 9590.8 9894.8 9969.0 10106.6 10118.2 10120.4 10321.4 10321.4 10326.6 10389.3 6097.6 7033.7 7584.5 7705.2 7785.3 8530.0 8543.8 8546.8 8605.4 8614.5 7530.7 7608.3 7627.5 7716.9 7800.5 7401.3 7510.9 7519.0 7551.6 7647.2 7064.3 7071.9 7133.1 7156.6 7171.5 4565.3 4927.8 4930.1 5130.4 5131.9 5089.0 5103.3 5131.5 5170.8 5243.2 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4708.04 Median:5299.98 stddev:1453.24 (27.4%) stddev-sans-first:1418.67 23:06:00 INFO - PROCESS | 1663 | Values: 272.4 312.7 319.6 325.1 325.3 4521.7 5518.5 5596.9 5673.2 5692.9 4678.3 5564.5 5577.0 5584.2 5602.1 3986.5 4130.8 4185.9 4210.4 4219.6 3337.9 4039.3 4129.8 4165.7 4220.8 3547.5 3549.8 3858.9 3964.6 4264.2 3913.9 4216.2 4254.4 4261.0 4272.0 3356.4 3560.1 3663.5 3683.4 3690.7 5389.5 5394.3 5418.4 5418.8 5514.5 4342.3 4517.6 4852.8 4874.6 4948.7 5213.5 5844.1 5869.4 5871.7 5921.7 4071.6 4076.2 4078.5 4085.4 4087.9 5591.5 5841.2 6160.4 6199.5 6244.2 3467.6 3533.3 4118.3 4133.2 4145.3 3407.4 3746.5 4167.9 4178.9 4222.2 4515.3 4818.6 4831.5 4902.8 4915.7 5907.5 5912.7 5928.8 5929.9 5937.9 1308.3 1421.7 1449.3 1452.4 1460.0 864.9 906.4 906.7 917.5 966.8 4774.3 5258.7 5432.3 5458.9 5471.8 4659.0 4727.4 5403.4 5969.6 6013.1 4622.5 4931.0 5060.2 5254.6 5313.3 2736.8 2899.4 3132.0 3149.6 3189.3 4938.8 5854.4 5895.7 5898.0 6167.9 5635.6 5644.7 6017.3 6119.3 6148.6 6045.9 6213.4 6273.8 6290.0 6323.6 5027.3 5381.4 6006.5 6012.4 6014.6 5271.7 5735.5 5882.0 5931.4 5960.2 5979.3 6213.8 6218.3 6251.4 6262.9 4078.3 4089.2 4110.2 4112.0 4120.5 5868.3 5929.9 5974.9 6010.2 6037.6 4776.0 5424.5 5790.9 5813.4 5815.8 5196.8 5286.7 5466.2 5714.6 5745.7 5525.2 5618.8 5650.5 5657.6 5709.4 4825.6 4833.9 5861.5 5869.5 5890.6 4708.8 5199.0 5713.6 5720.7 5775.4 23:06:00 INFO - PROCESS | 1663 | 23:06:00 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18642.18 Median:47121.17 stddev:25752.12 (54.7%) stddev-sans-first:25789.27 23:06:00 INFO - PROCESS | 1663 | Values: 739.2 741.4 764.6 779.8 782.9 16066.4 16459.0 16761.4 16827.1 16865.5 15846.8 15930.5 15936.9 15974.0 16016.5 717.2 875.9 897.5 913.4 923.4 905.2 911.3 911.4 914.1 916.3 952.6 956.9 958.4 959.2 963.6 914.3 916.4 949.5 955.0 956.0 695.0 697.5 709.8 710.8 711.7 14542.9 14592.4 14623.0 14646.0 14677.5 13274.7 13416.2 13458.5 13485.7 13591.7 9422.7 11374.7 11385.0 11465.0 11828.9 3447.9 3538.0 3718.5 3723.2 3887.8 63311.0 81398.6 81866.0 82070.0 82445.0 9337.2 10373.4 10421.8 10428.0 10671.6 17468.5 19373.5 20036.0 20112.4 20119.8 6640.4 7862.0 7868.1 7922.3 8123.2 4499.8 4518.8 4525.4 4536.9 4630.8 3339.2 3363.4 3393.0 3457.0 3466.6 599.9 607.5 627.1 655.7 658.1 556.7 626.5 642.3 666.6 693.5 9067.2 9090.1 9127.4 9144.0 9316.4 663.5 663.7 664.1 672.1 675.9 3756.6 4162.2 4291.3 4308.3 4461.0 95020.6 96967.0 97965.5 98182.0 98918.0 39831.0 84098.6 84171.6 84792.0 85463.1 71510.6 72450.6 72488.6 72731.3 72819.6 7140.1 8570.8 8602.9 8679.0 8805.7 46389.1 47023.5 47218.8 47486.3 48228.3 61433.0 65038.7 65443.4 65641.7 65787.0 11972.5 12122.6 12257.8 12287.4 12434.1 13601.7 13896.2 14068.4 14076.2 14604.4 12577.8 12583.9 12700.1 12778.6 12842.2 12666.9 12887.6 12904.9 12945.8 13382.0 12022.0 12214.9 12355.6 12424.3 12606.6 10796.5 10928.4 11009.1 11076.9 11336.9 10398.6 10852.6 10861.1 10877.6 10879.8 23:06:00 INFO - PROCESS | 1663 | -------- Summary: end -------- 23:06:00 INFO - PROCESS | 1663 | 23:06:01 INFO - TEST-INFO | 1663: exit 0 23:06:01 INFO - TEST-OK | dromaeo_css | took 1112109ms 23:06:01 INFO - TEST-START | kraken 23:06:01 INFO - operating with platform_type : mac_ 23:06:01 INFO - Initialising browser for kraken test... 23:06:01 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpqEeV55/profile http://localhost:49278/getInfo.html) 23:06:04 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 23:06:04 INFO - PROCESS | 1671 | colorDepth:24 23:06:04 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 23:06:04 INFO - PROCESS | 1671 | __metrics 23:06:04 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 23:06:05 INFO - TEST-INFO | 1671: exit 0 23:06:05 INFO - Browser initialized. 23:06:05 INFO - Running cycle 1/1 for kraken test... 23:06:05 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpqEeV55/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:06:17 INFO - PROCESS | 1673 | RSS: Main: 252657664 23:06:17 INFO - PROCESS | 1673 | 23:06:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 23:06:21 INFO - PROCESS | 1673 | RSS: Main: 265441280 23:06:21 INFO - PROCESS | 1673 | 23:06:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 23:06:24 INFO - PROCESS | 1673 | RSS: Main: 269434880 23:06:24 INFO - PROCESS | 1673 | 23:06:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 23:06:28 INFO - PROCESS | 1673 | RSS: Main: 269926400 23:06:28 INFO - PROCESS | 1673 | 23:06:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 23:06:31 INFO - PROCESS | 1673 | RSS: Main: 270794752 23:06:31 INFO - PROCESS | 1673 | 23:06:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:06:33 INFO - PROCESS | 1673 | RSS: Main: 271441920 23:06:33 INFO - PROCESS | 1673 | 23:06:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 23:06:37 INFO - PROCESS | 1673 | RSS: Main: 271818752 23:06:37 INFO - PROCESS | 1673 | 23:06:40 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 23:06:40 INFO - PROCESS | 1673 | RSS: Main: 272089088 23:06:40 INFO - PROCESS | 1673 | 23:06:43 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 23:06:44 INFO - PROCESS | 1673 | RSS: Main: 272748544 23:06:44 INFO - PROCESS | 1673 | 23:06:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:06:46 INFO - PROCESS | 1673 | RSS: Main: 271933440 23:06:46 INFO - PROCESS | 1673 | 23:06:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:06:49 INFO - PROCESS | 1673 | RSS: Main: 271904768 23:06:49 INFO - PROCESS | 1673 | 23:06:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:06:52 INFO - PROCESS | 1673 | RSS: Main: 272269312 23:06:52 INFO - PROCESS | 1673 | 23:06:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:06:55 INFO - PROCESS | 1673 | RSS: Main: 272379904 23:06:55 INFO - PROCESS | 1673 | 23:06:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:06:59 INFO - PROCESS | 1673 | RSS: Main: 272396288 23:06:59 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 23:07:01 INFO - PROCESS | 1673 | RSS: Main: 273436672 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | __start_tp_report 23:07:01 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 23:07:01 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 23:07:01 INFO - PROCESS | 1673 | |i|pagename|runs| 23:07:01 INFO - PROCESS | 1673 | |0;ai-astar;116;111;116;116;114;109;155;169;136;116 23:07:01 INFO - PROCESS | 1673 | |1;audio-beat-detection;155;119;141;165;109;171;165;144;164;123 23:07:01 INFO - PROCESS | 1673 | |2;audio-dft;195;194;206;246;264;254;195;193;195;233 23:07:01 INFO - PROCESS | 1673 | |3;audio-fft;69;81;75;136;72;83;80;67;123;59 23:07:01 INFO - PROCESS | 1673 | |4;audio-oscillator;70;72;116;82;76;77;110;69;72;114 23:07:01 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;86;89;86;90;87;108;111;133;106 23:07:01 INFO - PROCESS | 1673 | |6;imaging-darkroom;103;96;97;96;97;96;97;97;96;96 23:07:01 INFO - PROCESS | 1673 | |7;imaging-desaturate;149;147;101;94;93;91;93;90;92;94 23:07:01 INFO - PROCESS | 1673 | |8;json-parse-financial;58;57;61;59;58;58;58;59;56;57 23:07:01 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;66;91;109;105;112;84;74;71;70;69 23:07:01 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;97;97;76;97;112;110;109;102;121;88 23:07:01 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;149;152;144;148;152;158;159;136;147;154 23:07:01 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;165;171;163;205;228;177;233;226;177;216 23:07:01 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;66;67;64;74;78;72;68;54;83 23:07:01 INFO - PROCESS | 1673 | __end_tp_report 23:07:01 INFO - PROCESS | 1673 | __start_cc_report 23:07:01 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1073 23:07:01 INFO - PROCESS | 1673 | __end_cc_report 23:07:01 INFO - PROCESS | 1673 | __startTimestamp1464674821514__endTimestamp 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | ------- Summary: start ------- 23:07:01 INFO - PROCESS | 1673 | Number of tests: 14 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:125.80 Median:116.00 stddev:20.68 (17.8%) stddev-sans-first:21.62 23:07:01 INFO - PROCESS | 1673 | Values: 116.0 111.0 116.0 116.0 114.0 109.0 155.0 169.0 136.0 116.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:145.60 Median:159.50 stddev:22.13 (13.9%) stddev-sans-first:23.21 23:07:01 INFO - PROCESS | 1673 | Values: 155.0 119.0 141.0 165.0 109.0 171.0 165.0 144.0 164.0 123.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:217.50 Median:219.50 stddev:28.58 (13.0%) stddev-sans-first:29.13 23:07:01 INFO - PROCESS | 1673 | Values: 195.0 194.0 206.0 246.0 264.0 254.0 195.0 193.0 195.0 233.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:84.50 Median:73.50 stddev:24.97 (34.0%) stddev-sans-first:25.85 23:07:01 INFO - PROCESS | 1673 | Values: 69.0 81.0 75.0 136.0 72.0 83.0 80.0 67.0 123.0 59.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:72.00 stddev:19.42 (27.0%) stddev-sans-first:19.74 23:07:01 INFO - PROCESS | 1673 | Values: 70.0 72.0 116.0 82.0 76.0 77.0 110.0 69.0 72.0 114.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:86.50 stddev:15.67 (18.1%) stddev-sans-first:16.16 23:07:01 INFO - PROCESS | 1673 | Values: 88.0 86.0 89.0 86.0 90.0 87.0 108.0 111.0 133.0 106.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:97.10 Median:96.50 stddev:2.13 (2.2%) stddev-sans-first:0.53 23:07:01 INFO - PROCESS | 1673 | Values: 103.0 96.0 97.0 96.0 97.0 96.0 97.0 97.0 96.0 96.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:104.40 Median:92.50 stddev:23.17 (25.1%) stddev-sans-first:18.10 23:07:01 INFO - PROCESS | 1673 | Values: 149.0 147.0 101.0 94.0 93.0 91.0 93.0 90.0 92.0 94.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:58.00 stddev:1.37 (2.4%) stddev-sans-first:1.45 23:07:01 INFO - PROCESS | 1673 | Values: 58.0 57.0 61.0 59.0 58.0 58.0 58.0 59.0 56.0 57.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:85.10 Median:70.50 stddev:17.95 (25.5%) stddev-sans-first:17.66 23:07:01 INFO - PROCESS | 1673 | Values: 66.0 91.0 109.0 105.0 112.0 84.0 74.0 71.0 70.0 69.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:100.90 Median:82.00 stddev:12.95 (15.8%) stddev-sans-first:13.66 23:07:01 INFO - PROCESS | 1673 | Values: 97.0 97.0 76.0 97.0 112.0 110.0 109.0 102.0 121.0 88.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:149.90 Median:152.00 stddev:6.79 (4.5%) stddev-sans-first:7.19 23:07:01 INFO - PROCESS | 1673 | Values: 149.0 152.0 144.0 148.0 152.0 158.0 159.0 136.0 147.0 154.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.10 Median:210.50 stddev:28.23 (13.4%) stddev-sans-first:27.60 23:07:01 INFO - PROCESS | 1673 | Values: 165.0 171.0 163.0 205.0 228.0 177.0 233.0 226.0 177.0 216.0 23:07:01 INFO - PROCESS | 1673 | 23:07:01 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:70.00 stddev:8.05 (11.5%) stddev-sans-first:8.46 23:07:01 INFO - PROCESS | 1673 | Values: 66.0 66.0 67.0 64.0 74.0 78.0 72.0 68.0 54.0 83.0 23:07:01 INFO - PROCESS | 1673 | -------- Summary: end -------- 23:07:01 INFO - PROCESS | 1673 | 23:07:02 INFO - TEST-INFO | 1673: exit 0 23:07:02 INFO - TEST-OK | kraken | took 60792ms 23:07:02 INFO - SUITE-END | took 1172s 23:07:02 INFO - Completed test suite (00:19:33) 23:07:02 INFO - javascript benchmark 23:07:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7246.408325529933, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.4665726812354, 3472.808995505247, 3487.6686714762104, 3509.8947031589046, 3511.0169152637263, 5703.230063941367, 5814.302283725953, 5821.359650438449, 5824.621399609023, 5850.912236316453, 4572.611328037121, 5767.567432442563, 5804.738786754596, 5813.128030795372, 5820.592558520905, 5170.457102004292, 5255.553277971358, 5302.2311764794085, 5312.0, 5366.329208848894, 222.85291358039078, 5320.962412329597, 5328.254044433782, 5331.973340133294, 5398.649087809299, 5250.081235783741, 5250.264962905197, 5252.36971563411, 5284.735763211863, 5301.363836339645, 5191.922121168148, 5310.0, 5326.9467305327225, 5342.332208473941, 5357.624966252325, 5022.472640372781, 5029.471905449912, 5071.923921141186, 5076.492350764931, 5094.541491265803, 5488.423715509894, 5518.696471694056, 5529.695866727328, 5532.087205611069, 5638.126090455986, 6015.789447369365, 6079.148919151322, 6110.358412366726, 6116.938830611726, 6128.172696685935, 6262.937370626281, 6275.435210831001, 6288.465480434123, 6334.936650633481, 6336.619802811846, 5912.822615321547, 5920.141579470953, 5942.6731529766275, 5951.845521873414, 6008.158857759873, 6214.8135555933395, 6248.875022499616, 6271.264733200557, 6282.089097080898, 6311.810645680545, 6262.874742505124, 6271.216097987752, 6295.213098362705, 6301.180846489927, 6301.40136687014, 6263.906041409383, 6270.435660790551, 6286.748530058838, 6301.4328710416285, 6317.905231421532, 4627.282771170474, 5357.678539287655, 6124.5100391968535, 6130.908036379458, 6273.12176295319, 6102.0651310563235, 6207.317195108534, 6269.65516896566, 6283.7800676977, 6313.337099604567, 4992.90014199714, 5056.393232812013, 5066.316047333563, 5066.569341606005, 5095.184770436712, 4457.375967364572, 4458.375827384169, 4482.574155455227, 4510.143072816154, 4572.634189264851, 5926.318473375531, 5961.970190149022, 5966.820995370146, 5997.760089596367, 6027.619090459244, 5434.864128396822, 6162.829062478115, 6288.496920246369, 6316.0, 6320.209973753281, 5711.485966262973, 5805.245318108703, 5845.912311315334, 5909.084091965752, 5910.615809972338, 5511.063119269734, 5530.668159910338, 5535.391106978229, 5537.1140617501815, 5546.140348246028, 6417.326180751047, 6420.7431702731365, 6442.098106265126, 6467.126937863422, 6479.352064793483, 6217.875642487215, 6247.281562620356, 6251.312355640966, 6286.0, 6332.968335158294, 6364.236291645032, 6375.139356186855, 6396.776112836028, 6398.392152745481, 6430.517711171775, 6138.57030007913, 6268.874622507433, 6269.122322874892, 6272.0, 6284.968575157095, 6179.81460556184, 6270.874582508415, 6306.117143599808, 6323.620582765049, 6329.113924050729, 6198.535109866868, 6200.534959878118, 6222.968885155727, 6239.875202495834, 6243.843903902439, 6051.364606716232, 6098.237720284965, 6129.325774164928, 6202.193714817045, 6206.658633775186, 5185.0148471790235, 5690.829275121753, 5999.040153575407, 6049.879002419839, 6059.575829691879, 6176.197094377702, 6184.134221209124, 6212.502999760099, 6261.0, 6272.059191121369, 5921.319048309328, 6039.335673076046, 6043.425874541911, 6068.4538391544975, 6113.724882380393], "value": 5706.10616465992, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.88804463018147, 517.888653939403, 529.7007190937118, 539.1454544546893, 545.5471958274632, 38284.617153828454, 40597.7970110149, 40645.390319145234, 40660.39009414861, 40712.18575628486, 42286.78856605721, 42885.0, 42896.78551607238, 42976.35535466971, 43130.784346078304, 9363.85954210684, 9369.625214991393, 9568.282378821581, 9573.282003849705, 9596.520173991272, 9334.766630834249, 9435.198008169315, 9446.66936657217, 9456.432614043146, 9482.952585237099, 9441.103095205945, 9460.810783784354, 9469.337146399756, 9470.384425012386, 9481.288903332243, 9432.622695092188, 9468.242540596735, 9475.147236748691, 9487.0, 9509.191718703849, 9358.204552613037, 9486.0, 9513.66702165421, 9522.619095236183, 9523.857142142873, 38699.22601547953, 38735.418968715494, 39082.804585977174, 39115.60884391148, 39381.40927886084, 3060.8066948280775, 3364.5121457388786, 3473.218525831693, 3498.8250587470525, 3569.4824250483534, 29296.70703292961, 29915.700842991508, 30633.927812526454, 30810.84594577035, 31150.688493115005, 2604.5311843868285, 3064.2045199263302, 3077.569140320357, 3092.932648897259, 3096.4426403247408, 379686.0, 462146.0, 465549.0, 465814.0, 468050.99999999657, 33645.83177084099, 33674.663253367646, 33780.15549611279, 33883.32233355319, 33902.135382554196, 34293.82853085719, 34659.82670086633, 34756.65243347585, 35230.295394091976, 35814.820925895205, 16617.50147495577, 16720.74918876218, 16833.0, 16834.494965151065, 16871.32514699423, 12785.424655890507, 12858.807117893239, 12886.29125398112, 12892.0, 12942.547010854574, 16491.58771030734, 17069.40257090996, 18970.051497425073, 19317.903410482857, 19541.0, 7960.0, 8058.6959346668755, 8109.0, 8137.552434616153, 8147.918520814835, 1015.827309357411, 1051.6687243518268, 1072.0, 1075.0112292259257, 1084.4415126209851, 29182.0, 30057.94797182132, 30387.848060759556, 30598.0, 30939.69060309413, 1094.441834664311, 1100.49927283086, 1102.0742576236, 1139.32779660001, 1243.664210663116, 11983.880161198276, 15172.61256963392, 15586.376544938075, 15821.288042038135, 16022.519324420286, 166728.0, 175521.4895702079, 191314.0, 192525.07474925072, 192565.0, 58699.0, 58701.0, 59453.702731486934, 59475.40524594699, 59719.40280597138, 1422.4807945100172, 1433.175923843794, 1439.0574173916102, 1448.9565313040837, 1453.3605213705935, 30138.54792178163, 31172.908948186705, 31428.528572071893, 31986.0, 32403.51394729128, 57702.7114864423, 57779.71110144423, 58664.413355865894, 58709.41290587039, 58803.70598146982, 585258.0, 588508.0, 588619.0, 594085.0, 594809.0, 27073.45853082888, 27858.746356414416, 27892.442151156458, 44631.0, 45167.548324516334, 47815.521844781106, 48657.0, 49531.25703114384, 49543.50456495533, 49785.50214497808, 38585.8424247273, 40205.597944020185, 40243.39634905537, 40969.0, 40997.97505062397, 38580.228395432496, 38817.2236555273, 39305.0, 39632.60367396405, 39714.801425992686, 38278.0, 38496.2300753989, 38695.41956870705, 39144.41283380695, 39150.80424597859, 25120.74879251184, 32754.0, 34843.78046768351, 35225.0, 35703.0, 21430.57138857183, 25446.74553254444, 25633.487330252916, 25704.742952570236, 26110.738892610832], "value": 19003.51726425324, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.926700759337, 358.1386764830582, 379.62417206965114, 385.10078965615287, 399.2812936713916, 14546.490872819453, 20033.19867205312, 20307.08618112185, 21162.047707853144, 21230.46923826903, 11559.422028898542, 12206.694832629166, 20062.89968550159, 20514.897425512852, 20543.589128217427, 5062.544371006617, 5109.3613298337705, 5138.691678499284, 5187.922181167267, 5217.060929032773, 5352.009878172531, 5667.4615911488345, 6051.364606716297, 6060.666663333515, 6096.237970253718, 6070.72681729321, 6081.148639190517, 6133.325334213233, 6837.829054273658, 6858.0, 6232.501399887998, 6829.7609583664835, 6856.280090590466, 6857.691403886812, 6873.862522749542, 5973.253343332083, 5989.281286245635, 6010.429009244115, 6014.8195554133445, 6019.157317975487, 24654.630180547305, 24761.009559617774, 25255.98976040956, 25404.364890877692, 25737.227883163534, 2111.4193596761015, 2143.046344376753, 2307.8730669813153, 2357.728861180969, 2395.4849707312837, 10207.6427325044, 10263.332883362558, 10343.120834729058, 10410.219233557438, 10513.579456821719, 1860.0978525415162, 1894.1760334254643, 1896.3268039845793, 1983.2959299448776, 2002.879827210372, 127913.0, 132115.0, 133300.0, 133832.0, 134002.65997340097, 11589.594364197214, 11858.0, 11859.58491452795, 11882.524699011943, 12006.819897701542, 14801.555953321418, 14919.40322387114, 14954.252287385587, 14961.40154393812, 14962.476313328982, 7963.8407231855035, 7980.920190798018, 8200.384971127189, 8510.36172287081, 8742.213200811957, 7807.0, 7894.526328420313, 8475.957620211859, 8546.658133674708, 8589.785255368666, 4474.6420286377015, 4612.562589856711, 4618.699784513996, 4645.094206629742, 4649.372334734835, 1925.7977912319284, 1974.940751777449, 1977.812107849752, 1988.7315212446135, 2024.9291274805312, 846.6147902704255, 879.9453276599085, 895.83427065993, 924.6301479408156, 953.2326477185932, 20208.19167233294, 20946.476338091365, 21074.946252687612, 21319.89340053321, 21331.68002479964, 865.8008658008567, 915.8992510823804, 928.9303302252358, 989.3371441134457, 997.0777031246068, 9548.854137503544, 10931.56273749057, 11026.889731102747, 11217.270877392943, 11247.987681108576, 620036.0, 621638.0, 623667.0, 632694.0, 636250.0, 89080.0, 119672.0, 133543.0, 137896.0, 145203.27398363152, 856.7558245899888, 1009.6466236817055, 1058.317385286486, 1096.555894862582, 1103.2938919091787, 14629.92685036568, 15459.381624735335, 17761.02314372722, 18523.0738463079, 19064.523386915436, 55213.79144834162, 56273.155902662314, 57508.137377938525, 58271.251862444195, 59207.703961479914, 84114.0, 84322.15677843142, 84654.56718201606, 85627.57186214029, 85806.0, 7955.761327160194, 7990.161033091442, 8112.148224436349, 8141.104478507478, 8308.5430301334, 11792.351420671945, 12383.504659813507, 12492.687682808002, 12680.492780289054, 12714.61856144317, 9772.729545159369, 10187.0, 10412.32319899204, 10767.246292759432, 11200.38397888124, 10692.037716605542, 10827.133829293794, 10836.099004358583, 10863.022327990519, 11069.446527673423, 11119.332840029625, 11331.603393881174, 11403.0, 11434.656960291204, 11445.599404020819, 8053.154418786177, 8104.432689711663, 8150.388720845842, 8261.545614990993, 8290.502569845828, 9497.810043799223, 11141.665750027512, 11263.26788758728, 11272.605458808903, 11293.71765705864], "value": 9605.214412513888, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.5329857900444, 1471.1393834606752, 1616.2242123780586, 1785.9017754023523, 1827.6710192165406, 7612.77161685149, 7976.920230797705, 8571.142885711426, 8985.640574377034, 9005.819883602333, 8693.69572064978, 8746.387752857303, 8838.160374764404, 8886.244669203126, 8897.466152030882, 2359.622460406331, 2386.3556839653284, 2391.390195500145, 2395.1257790906366, 2395.317334559651, 2377.7978871795917, 2385.2486466762916, 2419.129113519132, 2424.963625545618, 2428.0651948999584, 2363.385519764865, 2368.656544801003, 2375.809935205188, 2378.9413710898657, 2399.160293897131, 2350.9059637614473, 2365.38499990003, 2373.738888722239, 2384.892679829412, 2390.1395497620842, 2327.8254130940245, 2336.2523992322463, 2348.178137651825, 2364.9054037838464, 2371.063429945162, 8769.517676527788, 8797.824043519095, 8806.20744133031, 8900.688475903313, 8932.598033088527, 5300.496452836975, 5427.755750991215, 5459.535939445152, 5463.562914966793, 5468.043092458844, 3073.9097413758636, 3510.8244587770764, 3574.4995700601935, 3626.873059442933, 3655.94516082259, 991.6430085169334, 994.7363948553639, 1001.394156535293, 1067.6156583629886, 1069.3744159666605, 11488.942555287254, 11600.6519804406, 11624.535018599247, 11630.953214210762, 11698.06415486759, 4806.639502037291, 4811.422629284508, 4859.829905953346, 4878.658493905393, 4933.827316043921, 8293.668253269921, 9930.652427165014, 9956.601735930482, 9962.153216976492, 9982.401055936667, 5296.708681022581, 5312.282841816305, 5373.7850485980125, 5388.137897936312, 5407.134858422633, 4435.467743870756, 4771.117343291502, 4944.505549445027, 4949.183384741477, 4955.281484184773, 3979.6816254699556, 4049.8987525311513, 4065.5121385433936, 4107.014316564063, 4174.874753757332, 1120.3642681684669, 1296.5915736542959, 1314.7173357728134, 1315.7171208190284, 1317.5915466205506, 933.4446004627235, 942.3686130292721, 946.8721722567503, 953.8378475659155, 985.4235272365746, 5903.114532820111, 5974.163617093611, 6053.7578496860515, 6124.816255512342, 6136.631802091889, 413.65768082571645, 433.4646711311528, 434.73698412460004, 443.85352833564843, 448.2491826191182, 3759.830807613664, 4112.91774164515, 4124.52567954683, 4138.606832350922, 4162.937555936664, 10311.742206444898, 10671.626493072705, 10691.62579309736, 10733.78532429347, 10742.946285268523, 10262.230332725076, 10592.57629694819, 10731.409772462433, 10750.73123171927, 10800.0, 8093.27160555541, 8144.0, 8163.877541836877, 8188.0, 8267.503949763033, 5340.973295133655, 5521.917171242515, 5894.410558944071, 5982.282126144891, 6085.087236914321, 9590.760230994281, 9894.752631184278, 9969.003099689973, 10106.595736170471, 10118.190544756255, 10120.392776433437, 10321.380717156995, 10321.432321222404, 10326.586936522455, 10389.324693894872, 6097.5731698780655, 7033.683484243119, 7584.469087163846, 7705.152433232228, 7785.260400261966, 8530.0, 8543.829123417621, 8546.78633034179, 8605.440646357967, 8614.483131012159, 7530.698772049057, 7608.31525162738, 7627.54234745917, 7716.92283077162, 7800.492967957065, 7401.259874012771, 7510.887336689845, 7518.962405187939, 7551.622418878924, 7647.19704431049, 7064.293570642894, 7071.893921591078, 7133.108361454818, 7156.606386648784, 7171.49799514029, 4565.338025986147, 4927.753612319441, 4930.087933732271, 5130.384353877558, 5131.87170320745, 5089.033083714083, 5103.285540024473, 5131.4868513148385, 5170.767315470888, 5243.23973023917], "value": 4526.435624453669, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.43877612119036, 312.66857131440673, 319.6340190481898, 325.12379138222485, 325.263278673804, 4521.660875434339, 5518.530924871391, 5596.888062238752, 5673.23411339469, 5692.886142277157, 4678.298255261712, 5564.527015203713, 5576.972115139429, 5584.162375643656, 5602.142907117379, 3986.481757371545, 4130.772807495587, 4185.890738954179, 4210.3684447332835, 4219.578042195786, 3337.9499307510405, 4039.313316736162, 4129.834806607732, 4165.729227600212, 4220.788960551976, 3547.4856145858835, 3549.7515173937836, 3858.9228215435674, 3964.603539646041, 4264.189803937242, 3913.882583522499, 4216.2199993001395, 4254.446921900164, 4260.97869510652, 4271.992163683095, 3356.3622911646708, 3560.0743806610208, 3663.4871118043493, 3683.3977219909425, 3690.6678398943955, 5389.51494365509, 5394.298741163663, 5418.403975562725, 5418.810341638064, 5514.476124768141, 4342.34864770287, 4517.638588912879, 4852.811061290006, 4874.634402419797, 4948.67833590819, 5213.504717051874, 5844.123381492767, 5869.442402971754, 5871.706414679292, 5921.713449035783, 4071.6131967463043, 4076.2255171517318, 4078.4697989261504, 4085.407615895708, 4087.9386809197886, 5591.46881046304, 5841.211436456067, 6160.398296442912, 6199.473044791198, 6244.1570387997945, 3467.6185619581825, 3533.311004354178, 4118.341065429517, 4133.152703695795, 4145.274576949082, 3407.4037043517287, 3746.456763769238, 4167.874963751093, 4178.937315940263, 4222.1766755483, 4515.322701594721, 4818.614510839125, 4831.51684831514, 4902.779374928136, 4915.729634870117, 5907.497862681635, 5912.733926973297, 5928.822135335947, 5929.851753706105, 5937.910931336033, 1308.2542950518305, 1421.7369786589513, 1449.311577000916, 1452.3609611770787, 1459.9635009124856, 864.900536438305, 906.4334790755778, 906.7415786500816, 917.5412293853074, 966.8163049020663, 4774.331593576832, 5258.71077090756, 5432.29380180574, 5458.925608268833, 5471.835844924659, 4659.0, 4727.409073865767, 5403.432639572867, 5969.552283578749, 6013.067974463965, 4622.520793346097, 4931.0, 5060.1903695408555, 5254.632175747661, 5313.256144139824, 2736.7947403944786, 2899.405621847516, 3131.9507964831582, 3149.5748074009716, 3189.28241145745, 4938.789996450821, 5854.443827836434, 5895.698408891035, 5898.0, 6167.938320616737, 5635.5773317001385, 5644.6613203207935, 6017.308009578867, 6119.265688117454, 6148.631082135086, 6045.879082418415, 6213.378662133841, 6273.811785646438, 6289.96855015722, 6323.620582765049, 5027.271045698426, 5381.408045114956, 6006.489448396936, 6012.368701286477, 6014.6391216527145, 5271.71005594696, 5735.537437953295, 5881.9705901470215, 5931.436513531207, 5960.165576819162, 5979.2824861015215, 6213.782517611862, 6218.347073557211, 6251.4061164189325, 6262.906056409066, 4078.286299897565, 4089.241626100805, 4110.157417729418, 4112.0, 4120.484939382577, 5868.3251426086545, 5929.881402371842, 5974.880502389841, 6010.248718910137, 6037.607555508878, 4776.017952549653, 5424.511793938565, 5790.94209057904, 5813.447722466359, 5815.82552523425, 5196.844094677166, 5286.704757334323, 5466.234727138124, 5714.5999780015, 5745.683987380734, 5525.226468294361, 5618.775248989995, 5650.491455768836, 5657.603967722384, 5709.372828743788, 4825.5898248649255, 4833.89725469422, 5861.515940305584, 5869.501092407193, 5890.646561206341, 4708.835190768444, 5198.960207958499, 5713.600167958876, 5720.713964301852, 5775.364709881826], "value": 4244.3507024093415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.2201227704772, 741.3661319571765, 764.5871229536051, 779.8050487378156, 782.9217078292171, 16066.357345706172, 16459.0, 16761.413350532734, 16827.074510901897, 16865.494035178934, 15846.841531584681, 15930.52208433746, 15936.920315398407, 15974.0, 16016.519504414857, 717.1859938969255, 875.8642410426394, 897.4929165021774, 913.369774855351, 923.3767207135157, 905.248643625789, 911.3438324406417, 911.389369122686, 914.1407077347305, 916.3356566489308, 952.6046690623417, 956.8803899512561, 958.420155805738, 959.1799011844877, 963.5615794813343, 914.2594498456239, 916.422653728147, 949.5062567464948, 955.016333176829, 955.9904400956005, 695.0339028749991, 697.5012865800926, 709.7835160276114, 710.8400609862791, 711.6584039660992, 14542.927285363612, 14592.416303347853, 14623.04950178235, 14646.048006879626, 14677.48628797987, 13274.734505309936, 13416.195028298236, 13458.461661533527, 13485.662858428617, 13591.660208494768, 9422.717318480456, 11374.658760237206, 11385.032272256869, 11465.0, 11828.935395814418, 3447.931041379158, 3537.9739875436094, 3718.535183102112, 3723.1809002019513, 3887.7667339959694, 63311.0, 81398.59300703518, 81866.0, 82070.0, 82445.0, 9337.206337461392, 10373.377597344184, 10421.791564168674, 10428.009339112772, 10671.626493072705, 17468.47594572165, 19373.51566210856, 20036.0, 20112.39662810118, 20119.798802012087, 6640.435562977104, 7862.0, 7868.134505204423, 7922.286994170552, 8123.18768123183, 4499.752513611717, 4518.81924723014, 4525.434320709911, 4536.931946020813, 4630.791614377361, 3339.1652086978256, 3363.428217203081, 3393.0499460151195, 3456.9827150864085, 3466.6013408458352, 599.868029033612, 607.5048835199299, 627.0813258576208, 655.6852710698892, 658.0819756439739, 556.7340922445608, 626.5144513002459, 642.3191417097797, 666.6266890541312, 693.4625665109479, 9067.183953444222, 9090.13643703847, 9127.406718563272, 9143.954280228556, 9316.44101353921, 663.5156335874839, 663.6880666086931, 664.0769330630427, 672.0658284983876, 675.9087523184307, 3756.6055564165367, 4162.209180255742, 4291.313389857608, 4308.332208507686, 4460.955390446119, 95020.57469137969, 96967.0303296958, 97965.51017244869, 98182.0, 98918.0, 39831.00422489461, 84098.57950710329, 84171.5791421039, 84792.0, 85463.14536854676, 71510.56978860497, 72450.63774681093, 72488.63755681293, 72731.27268727245, 72819.63590182121, 7140.07179066718, 8570.78573035679, 8602.870956935525, 8679.047214376715, 8805.735827925173, 46389.07221855612, 47023.52976470192, 47218.81952951204, 47486.287705685136, 48228.27657585209, 61433.0, 65038.67480662566, 65443.38226470888, 65641.68716625737, 65787.0, 11972.521099155936, 12122.575709850466, 12257.816132758195, 12287.385630718607, 12434.13804963869, 13601.659958501117, 13896.235707035805, 14068.366923488264, 14076.155430674193, 14604.415823366946, 12577.811332829831, 12583.87416125827, 12700.110992230824, 12778.552750653682, 12842.229466232056, 12666.936665316614, 12887.613371598867, 12904.862579281184, 12945.805812913002, 13382.0, 12022.0, 12214.938925305316, 12355.62933111972, 12424.316662583284, 12606.55877044299, 10796.460176991277, 10928.398938058483, 11009.064229540258, 11076.889231107909, 11336.886631133582, 10398.64122289945, 10852.565897364017, 10861.131109511061, 10877.556509698075, 10879.782404352027], "value": 7235.114946715731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1618.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 111.0, 116.0, 116.0, 114.0, 109.0, 155.0, 169.0, 136.0, 116.0], "value": 125.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 119.0, 141.0, 165.0, 109.0, 171.0, 165.0, 144.0, 164.0, 123.0], "value": 145.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 194.0, 206.0, 246.0, 264.0, 254.0, 195.0, 193.0, 195.0, 233.0], "value": 217.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 75.0, 136.0, 72.0, 83.0, 80.0, 67.0, 123.0, 59.0], "value": 84.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 116.0, 82.0, 76.0, 77.0, 110.0, 69.0, 72.0, 114.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 90.0, 87.0, 108.0, 111.0, 133.0, 106.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 96.0, 97.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0, 96.0], "value": 97.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 101.0, 94.0, 93.0, 91.0, 93.0, 90.0, 92.0, 94.0], "value": 104.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 61.0, 59.0, 58.0, 58.0, 58.0, 59.0, 56.0, 57.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 91.0, 109.0, 105.0, 112.0, 84.0, 74.0, 71.0, 70.0, 69.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 76.0, 97.0, 112.0, 110.0, 109.0, 102.0, 121.0, 88.0], "value": 100.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 144.0, 148.0, 152.0, 158.0, 159.0, 136.0, 147.0, 154.0], "value": 149.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 171.0, 163.0, 205.0, 228.0, 177.0, 233.0, 226.0, 177.0, 216.0], "value": 196.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 64.0, 74.0, 78.0, 72.0, 68.0, 54.0, 83.0], "value": 69.2, "unit": "score"}]}]} 23:07:02 INFO - Return code: 0 23:07:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:07:02 ERROR - # TBPL SUCCESS # 23:07:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:07:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:07:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:07:02 INFO - 2016-05-30 23:07:02.798 screenresolution[1678:10492] starting screenresolution argv=screenresolution get 23:07:02 INFO - 2016-05-30 23:07:02.801 screenresolution[1678:10492] Display 0: 1600x1200x32@60 23:07:02 INFO - 2016-05-30 23:07:02.807 screenresolution[1679:10495] starting screenresolution argv=screenresolution list 23:07:02 INFO - Available Modes on Display 0 23:07:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:07:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:07:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:07:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:07:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:07:03 INFO - Intel Iris: 23:07:03 INFO - Chipset Model: Intel Iris 23:07:03 INFO - Type: GPU 23:07:03 INFO - Bus: Built-In 23:07:03 INFO - VRAM (Dynamic, Max): 1536 MB 23:07:03 INFO - Vendor: Intel (0x8086) 23:07:03 INFO - Device ID: 0x0a2e 23:07:03 INFO - Revision ID: 0x0009 23:07:03 INFO - Displays: 23:07:03 INFO - Display: 23:07:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:07:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:07:03 INFO - Display Serial Number: 1600x1200 60 23:07:03 INFO - Main Display: Yes 23:07:03 INFO - Mirror: Off 23:07:03 INFO - Online: Yes 23:07:03 INFO - Rotation: Supported 23:07:03 INFO - Return code: 0 23:07:03 INFO - Running post-action listener: _resource_record_post_action 23:07:03 INFO - ##### 23:07:03 INFO - ##### Finished run-tests step (success) 23:07:03 INFO - ##### 23:07:03 INFO - Running post-run listener: _resource_record_post_run 23:07:03 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 36709888; Write bytes: 697883648; Read time: 243; Write time: 1939 23:07:03 INFO - TinderboxPrint: CPU usage
54.6% 23:07:03 INFO - TinderboxPrint: I/O read bytes / time
36,709,888 / 243 23:07:03 INFO - TinderboxPrint: I/O write bytes / time
697,883,648 / 1,939 23:07:03 INFO - TinderboxPrint: CPU idle
2,193.9 (45.4%) 23:07:03 INFO - TinderboxPrint: CPU system
976.2 (20.2%) 23:07:03 INFO - TinderboxPrint: CPU user
1,667.7 (34.5%) 23:07:03 INFO - TinderboxPrint: Swap in / out
435,793,920 / 0 23:07:03 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 405484032; Write bytes: 402454016; Read time: 29784; Write time: 836 23:07:03 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 29588992; Write bytes: 294532608; Read time: 183; Write time: 1099 23:07:03 INFO - Running post-run listener: _upload_blobber_files 23:07:03 INFO - Blob upload gear active. 23:07:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:07:03 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 23:07:03 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'] 23:07:03 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 23:07:03 INFO - (blobuploader) - INFO - Open directory for files ... 23:07:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:07:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:07:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:07:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:07:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:07:04 INFO - (blobuploader) - INFO - Done attempting. 23:07:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:07:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:07:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:07:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:07:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:07:05 INFO - (blobuploader) - INFO - Done attempting. 23:07:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:07:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:07:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:07:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:07:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:07:08 INFO - (blobuploader) - INFO - Done attempting. 23:07:08 INFO - (blobuploader) - INFO - Iteration through files over. 23:07:08 INFO - Return code: 0 23:07:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:07:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:07:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d98cc42ff149557d83678b43071acf31bfdfa15dab3911585970515c91cfe0431d934dbd7e22facd6563a6bbbc4204d343dcf38ef7113f02e908982af0bd673c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff9894c12c81ab6b3ff1ba3ab5a5f3ef6fcdd038341f2d207fcde1f3b47993265d6285315a1b69326dd7dea06be1256d523fb2b38ba41d0969ee328d9831d780", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be78eb17eb1672b6cf2822356cc3374ed623d47198669f5c5a4f6f10b56af479d84b772429d324b282bca6575ddc5ce3edaa560a2622ad2f899121fd16300f9b"} 23:07:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:07:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:07:08 INFO - Contents: 23:07:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d98cc42ff149557d83678b43071acf31bfdfa15dab3911585970515c91cfe0431d934dbd7e22facd6563a6bbbc4204d343dcf38ef7113f02e908982af0bd673c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff9894c12c81ab6b3ff1ba3ab5a5f3ef6fcdd038341f2d207fcde1f3b47993265d6285315a1b69326dd7dea06be1256d523fb2b38ba41d0969ee328d9831d780", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be78eb17eb1672b6cf2822356cc3374ed623d47198669f5c5a4f6f10b56af479d84b772429d324b282bca6575ddc5ce3edaa560a2622ad2f899121fd16300f9b"} 23:07:08 INFO - Running post-run listener: copy_logs_to_upload_dir 23:07:08 INFO - Copying logs to upload dir... 23:07:08 INFO - mkdir: /builds/slave/test/build/upload/logs 23:07:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.650580 ========= master_lag: 0.84 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 37 secs) (at 2016-05-30 23:07:10.492547) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 23:07:10.496840) ========= 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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d98cc42ff149557d83678b43071acf31bfdfa15dab3911585970515c91cfe0431d934dbd7e22facd6563a6bbbc4204d343dcf38ef7113f02e908982af0bd673c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff9894c12c81ab6b3ff1ba3ab5a5f3ef6fcdd038341f2d207fcde1f3b47993265d6285315a1b69326dd7dea06be1256d523fb2b38ba41d0969ee328d9831d780", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be78eb17eb1672b6cf2822356cc3374ed623d47198669f5c5a4f6f10b56af479d84b772429d324b282bca6575ddc5ce3edaa560a2622ad2f899121fd16300f9b"} build_url:https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011078 build_url: 'https://queue.taskcluster.net/v1/task/S_609GmFQke5um3RFw96ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d98cc42ff149557d83678b43071acf31bfdfa15dab3911585970515c91cfe0431d934dbd7e22facd6563a6bbbc4204d343dcf38ef7113f02e908982af0bd673c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ff9894c12c81ab6b3ff1ba3ab5a5f3ef6fcdd038341f2d207fcde1f3b47993265d6285315a1b69326dd7dea06be1256d523fb2b38ba41d0969ee328d9831d780", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be78eb17eb1672b6cf2822356cc3374ed623d47198669f5c5a4f6f10b56af479d84b772429d324b282bca6575ddc5ce3edaa560a2622ad2f899121fd16300f9b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 23:07:10.527238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 23:07:10.527540) ========= 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.3KMZ2Yawip/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.iB1nxoxpGC/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.005192 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 23:07:10.654408) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-05-30 23:07:10.654713) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-05-30 23:07:14.079235) ========= ========= Total master_lag: 3.96 =========