builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0247 starttime: 1464037218.32 results: success (0) buildid: 20160523130801 builduid: 7111702919aa4d0f925a7ba95f4d8d09 revision: ec97adea519ba55ac05a2af28534e0594b97f80e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.317703) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.318426) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.318884) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.338633) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.338908) ========= 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.baDQHObdI7/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.mdhokoK7kC/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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-23 14:00:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.24M=0.001s 2016-05-23 14:00:18 (8.24 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121273 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.478447) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.478756) ========= 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.baDQHObdI7/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.mdhokoK7kC/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.021313 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:18.527194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 14:00:18.527572) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ec97adea519ba55ac05a2af28534e0594b97f80e --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 ec97adea519ba55ac05a2af28534e0594b97f80e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.baDQHObdI7/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.mdhokoK7kC/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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-23 14:00:18,584 truncating revision to first 12 chars 2016-05-23 14:00:18,585 Setting DEBUG logging. 2016-05-23 14:00:18,585 attempt 1/10 2016-05-23 14:00:18,585 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ec97adea519b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 14:00:20,052 unpacking tar archive at: fx-team-ec97adea519b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.726540 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 14:00:20.274530) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:20.275065) ========= script_repo_revision: ec97adea519ba55ac05a2af28534e0594b97f80e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:20.275708) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:20.276171) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 14:00:20.292667) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-23 14:00:20.293273) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.baDQHObdI7/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.mdhokoK7kC/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:00:20 INFO - MultiFileLogger online at 20160523 14:00:20 in /builds/slave/test 14:00:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:00:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:00:20 INFO - {'append_to_log': False, 14:00:20 INFO - 'base_work_dir': '/builds/slave/test', 14:00:20 INFO - 'blob_upload_branch': 'Fx-Team', 14:00:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:00:20 INFO - 'branch': 'Fx-Team', 14:00:20 INFO - 'buildbot_json_path': 'buildprops.json', 14:00:20 INFO - 'config_files': ('talos/mac_config.py',), 14:00:20 INFO - 'default_actions': ('clobber', 14:00:20 INFO - 'read-buildbot-config', 14:00:20 INFO - 'download-and-extract', 14:00:20 INFO - 'populate-webroot', 14:00:20 INFO - 'create-virtualenv', 14:00:20 INFO - 'install', 14:00:20 INFO - 'run-tests'), 14:00:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:00:20 INFO - 'download_minidump_stackwalk': True, 14:00:20 INFO - 'download_symbols': 'ondemand', 14:00:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:00:20 INFO - 'tooltool.py': '/tools/tooltool.py', 14:00:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:00:20 INFO - '/tools/misc-python/virtualenv.py')}, 14:00:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:00:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:00:20 INFO - 'installer_path': 'installer.exe', 14:00:20 INFO - 'log_level': 'info', 14:00:20 INFO - 'log_name': 'talos', 14:00:20 INFO - 'log_to_console': True, 14:00:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:00:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:00:20 INFO - 'opt_config_files': (), 14:00:20 INFO - 'pip_index': False, 14:00:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:00:20 INFO - 'cmd': ('bash', 14:00:20 INFO - '-c', 14:00:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:00:20 INFO - 'enabled': True, 14:00:20 INFO - 'halt_on_failure': False, 14:00:20 INFO - 'name': 'check_screen_resolution'},), 14:00:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:00:20 INFO - 'cmd': ('bash', 14:00:20 INFO - '-c', 14:00:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:00:20 INFO - 'enabled': True, 14:00:20 INFO - 'halt_on_failure': False, 14:00:20 INFO - 'name': 'check_screen_resolution'},), 14:00:20 INFO - 'run_cmd_checks_enabled': True, 14:00:20 INFO - 'sps_profile': False, 14:00:20 INFO - 'sps_profile_interval': 0, 14:00:20 INFO - 'suite': 'dromaeojs', 14:00:20 INFO - 'system_bits': '32', 14:00:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:00:20 INFO - 'title': 't-yosemite-r7-0247', 14:00:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:00:20 INFO - 'use_talos_json': True, 14:00:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:00:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:00:20 INFO - 'work_dir': 'build'} 14:00:20 INFO - ##### 14:00:20 INFO - ##### Running clobber step. 14:00:20 INFO - ##### 14:00:20 INFO - Running pre-action listener: _resource_record_pre_action 14:00:20 INFO - Running main action method: clobber 14:00:20 INFO - rmtree: /builds/slave/test/build 14:00:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:00:22 INFO - Running post-action listener: _resource_record_post_action 14:00:22 INFO - ##### 14:00:22 INFO - ##### Finished clobber step (success) 14:00:22 INFO - ##### 14:00:22 INFO - ##### 14:00:22 INFO - ##### Running read-buildbot-config step. 14:00:22 INFO - ##### 14:00:22 INFO - Running pre-action listener: _resource_record_pre_action 14:00:22 INFO - Running main action method: read_buildbot_config 14:00:22 INFO - Using buildbot properties: 14:00:22 INFO - { 14:00:22 INFO - "project": "", 14:00:22 INFO - "product": "firefox", 14:00:22 INFO - "script_repo_revision": "production", 14:00:22 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:00:22 INFO - "repository": "", 14:00:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:00:22 INFO - "buildid": "20160523130801", 14:00:22 INFO - "pgo_build": "False", 14:00:22 INFO - "basedir": "/builds/slave/test", 14:00:22 INFO - "buildnumber": 784, 14:00:22 INFO - "slavename": "t-yosemite-r7-0247", 14:00:22 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:00:22 INFO - "platform": "yosemite_r7", 14:00:22 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:00:22 INFO - "branch": "fx-team", 14:00:22 INFO - "repo_path": "integration/fx-team", 14:00:22 INFO - "revision": "ec97adea519ba55ac05a2af28534e0594b97f80e", 14:00:22 INFO - "stage_platform": "macosx64", 14:00:22 INFO - "builduid": "7111702919aa4d0f925a7ba95f4d8d09", 14:00:22 INFO - "slavebuilddir": "test" 14:00:22 INFO - } 14:00:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:00:22 INFO - Running post-action listener: _resource_record_post_action 14:00:22 INFO - ##### 14:00:22 INFO - ##### Finished read-buildbot-config step (success) 14:00:22 INFO - ##### 14:00:22 INFO - ##### 14:00:22 INFO - ##### Running download-and-extract step. 14:00:22 INFO - ##### 14:00:22 INFO - Running pre-action listener: _resource_record_pre_action 14:00:22 INFO - Running main action method: download_and_extract 14:00:22 INFO - mkdir: /builds/slave/test/build/tests 14:00: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')]} 14:00:22 INFO - https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:00:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:00:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:00:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:23 INFO - Downloaded 1199 bytes. 14:00:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:00:23 INFO - Using the following test package requirements: 14:00:23 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:00:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:00:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:00:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'jsshell-mac.zip'], 14:00:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:00:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:00:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:00:23 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:00:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:00:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:00:23 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:00:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:00:23 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')]} 14:00:23 INFO - https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:00:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:00:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:00:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:24 INFO - Downloaded 18630429 bytes. 14:00:24 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'] 14:00:24 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 14:00:27 INFO - Return code: 0 14:00:27 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 14:00: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')]} 14:00:27 INFO - https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:00:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:00:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:00:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:28 INFO - Downloaded 18630429 bytes. 14:00:28 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'] 14:00:28 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 14:00:32 INFO - Return code: 0 14:00:32 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')]} 14:00:32 INFO - https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:00:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:00:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:00:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:33 INFO - Downloaded 10840359 bytes. 14:00:33 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'] 14:00:33 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 14:00:33 INFO - Return code: 0 14:00: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')]} 14:00:33 INFO - https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:00:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:00:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:00:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:00:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IYr2NLyURuajldZr-yH0_w/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 14:00:36 INFO - Downloaded 110153605 bytes. 14:00:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:00:36 INFO - mkdir: /builds/slave/test/properties 14:00:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:00:36 INFO - Writing to file /builds/slave/test/properties/build_url 14:00:36 INFO - Contents: 14:00:36 INFO - build_url:https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:00:37 INFO - Running post-action listener: _resource_record_post_action 14:00:37 INFO - Running post-action listener: set_extra_try_arguments 14:00:37 INFO - ##### 14:00:37 INFO - ##### Finished download-and-extract step (success) 14:00:37 INFO - ##### 14:00:37 INFO - ##### 14:00:37 INFO - ##### Running populate-webroot step. 14:00:37 INFO - ##### 14:00:37 INFO - Running pre-action listener: _resource_record_pre_action 14:00:37 INFO - Running main action method: populate_webroot 14:00:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:00:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:00:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:00:37 INFO - '--tppagecycles', 14:00:37 INFO - '7'], 14:00:37 INFO - 'tests': ['tsvgm']}}, 14:00:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:00:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:00:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:00:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:00:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:00:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['damp', 'tps']}, 14:00:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'tests': ['damp', 'tps']}, 14:00:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['dromaeo_dom']}, 14:00:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:00:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['a11yr', 14:00:37 INFO - 'ts_paint', 14:00:37 INFO - 'tpaint', 14:00:37 INFO - 'sessionrestore', 14:00:37 INFO - 'sessionrestore_no_auto_restore', 14:00:37 INFO - 'tabpaint']}, 14:00:37 INFO - 'other-e10s': {'tests': ['a11yr', 14:00:37 INFO - 'ts_paint', 14:00:37 INFO - 'tpaint', 14:00:37 INFO - 'sessionrestore', 14:00:37 INFO - 'sessionrestore_no_auto_restore', 14:00:37 INFO - 'tabpaint']}, 14:00:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:00:37 INFO - 'ts_paint', 14:00:37 INFO - 'tpaint', 14:00:37 INFO - 'sessionrestore', 14:00:37 INFO - 'sessionrestore_no_auto_restore', 14:00:37 INFO - 'tabpaint']}, 14:00:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:00:37 INFO - 'ts_paint', 14:00:37 INFO - 'tpaint', 14:00:37 INFO - 'sessionrestore', 14:00:37 INFO - 'sessionrestore_no_auto_restore', 14:00:37 INFO - 'tabpaint']}, 14:00:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['a11yr', 14:00:37 INFO - 'ts_paint', 14:00:37 INFO - 'tpaint', 14:00:37 INFO - 'sessionrestore', 14:00:37 INFO - 'sessionrestore_no_auto_restore', 14:00:37 INFO - 'tabpaint']}, 14:00:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['a11yr', 14:00:37 INFO - 'ts_paint', 14:00:37 INFO - 'tpaint', 14:00:37 INFO - 'sessionrestore', 14:00:37 INFO - 'sessionrestore_no_auto_restore', 14:00:37 INFO - 'tabpaint']}, 14:00:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['tsvgx', 14:00:37 INFO - 'tsvgr_opacity', 14:00:37 INFO - 'tart', 14:00:37 INFO - 'tscrollx', 14:00:37 INFO - 'cart']}, 14:00:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:00:37 INFO - 'tsvgr_opacity', 14:00:37 INFO - 'tart', 14:00:37 INFO - 'tscrollx', 14:00:37 INFO - 'cart']}, 14:00:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'talos_options': ['--disable-e10s'], 14:00:37 INFO - 'tests': ['tp5o']}, 14:00:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'tests': ['tp5o']}, 14:00:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'talos_options': ['--disable-e10s', 14:00:37 INFO - '--xperf_path', 14:00:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:37 INFO - 'tests': ['tp5n']}, 14:00:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:00:37 INFO - 'talos_options': ['--xperf_path', 14:00:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:00:37 INFO - 'tests': ['tp5n']}}, 14:00:37 INFO - 'talos.zip': {'path': '', 14:00:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:00:37 INFO - Running post-action listener: _resource_record_post_action 14:00:37 INFO - ##### 14:00:37 INFO - ##### Finished populate-webroot step (success) 14:00:37 INFO - ##### 14:00:37 INFO - ##### 14:00:37 INFO - ##### Running create-virtualenv step. 14:00:37 INFO - ##### 14:00:37 INFO - Running pre-action listener: _resource_record_pre_action 14:00:37 INFO - Running main action method: create_virtualenv 14:00:37 INFO - Creating virtualenv /builds/slave/test/build/venv 14:00:37 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 14:00:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:00:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:00:37 INFO - Using real prefix '/tools/python27' 14:00:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:00:38 INFO - Installing distribute.............................................................................................................................................................................................done. 14:00:41 INFO - Installing pip.................done. 14:00:41 INFO - Return code: 0 14:00:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:00:41 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')]} 14:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:41 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')]} 14:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:41 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:00:41 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 14:00:41 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 14:00:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:00:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:41 INFO - 'HOME': '/Users/cltbld', 14:00:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:41 INFO - 'LOGNAME': 'cltbld', 14:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:41 INFO - 'MOZ_NO_REMOTE': '1', 14:00:41 INFO - 'NO_EM_RESTART': '1', 14:00:41 INFO - 'PAGER': '/bin/cat', 14:00:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:41 INFO - 'PWD': '/builds/slave/test', 14:00:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:41 INFO - 'SHELL': '/bin/bash', 14:00:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:00:41 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:00:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:41 INFO - 'USER': 'cltbld', 14:00:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:41 INFO - 'XPC_FLAGS': '0x0', 14:00:41 INFO - 'XPC_SERVICE_NAME': '0', 14:00:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:41 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 14:00:41 INFO - Cleaning up... 14:00:41 INFO - Return code: 0 14:00:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:00:41 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')]} 14:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:41 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')]} 14:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:41 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:00:41 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 14:00:41 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 14:00:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:00:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:41 INFO - 'HOME': '/Users/cltbld', 14:00:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:41 INFO - 'LOGNAME': 'cltbld', 14:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:41 INFO - 'MOZ_NO_REMOTE': '1', 14:00:41 INFO - 'NO_EM_RESTART': '1', 14:00:41 INFO - 'PAGER': '/bin/cat', 14:00:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:41 INFO - 'PWD': '/builds/slave/test', 14:00:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:41 INFO - 'SHELL': '/bin/bash', 14:00:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:00:41 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:00:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:41 INFO - 'USER': 'cltbld', 14:00:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:41 INFO - 'XPC_FLAGS': '0x0', 14:00:41 INFO - 'XPC_SERVICE_NAME': '0', 14:00:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:41 INFO - Downloading/unpacking psutil>=3.1.1 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:00:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:44 INFO - Installing collected packages: psutil 14:00:44 INFO - Running setup.py install for psutil 14:00:44 INFO - building 'psutil._psutil_osx' extension 14:00:44 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 14:00:45 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 14:00:45 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 14:00:45 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 14:00:45 INFO - building 'psutil._psutil_posix' extension 14:00:45 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 14:00:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:00:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:00:45 INFO - ^ 14:00:45 INFO - 1 warning generated. 14:00:45 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 14:00:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:00:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:00:45 INFO - Successfully installed psutil 14:00:45 INFO - Cleaning up... 14:00:45 INFO - Return code: 0 14:00:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:00: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')]} 14:00:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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')]} 14:00:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:00: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:00: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 mozsystemmonitor==0.3 14:00:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:00:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:45 INFO - 'HOME': '/Users/cltbld', 14:00:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:45 INFO - 'LOGNAME': 'cltbld', 14:00:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:45 INFO - 'MOZ_NO_REMOTE': '1', 14:00:45 INFO - 'NO_EM_RESTART': '1', 14:00:45 INFO - 'PAGER': '/bin/cat', 14:00:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:45 INFO - 'PWD': '/builds/slave/test', 14:00:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:45 INFO - 'SHELL': '/bin/bash', 14:00:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:00:45 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:00:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:45 INFO - 'USER': 'cltbld', 14:00:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:45 INFO - 'XPC_FLAGS': '0x0', 14:00:45 INFO - 'XPC_SERVICE_NAME': '0', 14:00:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:00:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:00:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:00:48 INFO - Installing collected packages: mozsystemmonitor 14:00:48 INFO - Running setup.py install for mozsystemmonitor 14:00:48 INFO - Successfully installed mozsystemmonitor 14:00:48 INFO - Cleaning up... 14:00:48 INFO - Return code: 0 14:00:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:00:48 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')]} 14:00:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:48 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')]} 14:00:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:48 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:00:48 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 14:00:48 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 14:00:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:00:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:48 INFO - 'HOME': '/Users/cltbld', 14:00:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:48 INFO - 'LOGNAME': 'cltbld', 14:00:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:48 INFO - 'MOZ_NO_REMOTE': '1', 14:00:48 INFO - 'NO_EM_RESTART': '1', 14:00:48 INFO - 'PAGER': '/bin/cat', 14:00:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:48 INFO - 'PWD': '/builds/slave/test', 14:00:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:48 INFO - 'SHELL': '/bin/bash', 14:00:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:00:48 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:00:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:48 INFO - 'USER': 'cltbld', 14:00:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:48 INFO - 'XPC_FLAGS': '0x0', 14:00:48 INFO - 'XPC_SERVICE_NAME': '0', 14:00:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:48 INFO - Downloading/unpacking blobuploader==1.2.4 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:51 INFO - Downloading blobuploader-1.2.4.tar.gz 14:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:00:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:00:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00: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 14:00:52 INFO - Downloading docopt-0.6.1.tar.gz 14:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:00:52 INFO - Installing collected packages: blobuploader, requests, docopt 14:00:52 INFO - Running setup.py install for blobuploader 14:00:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:00:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:00:52 INFO - Running setup.py install for requests 14:00:53 INFO - Running setup.py install for docopt 14:00:53 INFO - Successfully installed blobuploader requests docopt 14:00:53 INFO - Cleaning up... 14:00:53 INFO - Return code: 0 14:00:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:53 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')]} 14:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:53 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')]} 14:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:53 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:00:53 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 14:00:53 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 14:00:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:00:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:53 INFO - 'HOME': '/Users/cltbld', 14:00:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:53 INFO - 'LOGNAME': 'cltbld', 14:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:53 INFO - 'MOZ_NO_REMOTE': '1', 14:00:53 INFO - 'NO_EM_RESTART': '1', 14:00:53 INFO - 'PAGER': '/bin/cat', 14:00:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:53 INFO - 'PWD': '/builds/slave/test', 14:00:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:53 INFO - 'SHELL': '/bin/bash', 14:00:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:00:53 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:00:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:53 INFO - 'USER': 'cltbld', 14:00:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:53 INFO - 'XPC_FLAGS': '0x0', 14:00:53 INFO - 'XPC_SERVICE_NAME': '0', 14:00:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:53 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-DfAhYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:53 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-q1J8vY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:53 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-GvD4Qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:53 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-YQ1TOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:53 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-jpZ4Js-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-JBmvZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-F4BCGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-68viQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-VbCaov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Qpbwxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-fWPRiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-Qo6E9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-xNPLCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:54 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-tVgNj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:55 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-5AAtPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:55 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-eysai9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:55 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-LbcPOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:00:55 INFO - Running setup.py install for manifestparser 14:00:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:00:55 INFO - Running setup.py install for mozcrash 14:00:55 INFO - Running setup.py install for mozdebug 14:00:55 INFO - Running setup.py install for mozdevice 14:00:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:00:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:00:55 INFO - Running setup.py install for mozfile 14:00:56 INFO - Running setup.py install for mozhttpd 14:00:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Running setup.py install for mozinfo 14:00:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Running setup.py install for mozInstall 14:00:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Running setup.py install for mozleak 14:00:56 INFO - Running setup.py install for mozlog 14:00:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Running setup.py install for moznetwork 14:00:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:00:56 INFO - Running setup.py install for mozprocess 14:00:57 INFO - Running setup.py install for mozprofile 14:00:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:00:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:00:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:00:57 INFO - Running setup.py install for mozrunner 14:00:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:00:57 INFO - Running setup.py install for mozscreenshot 14:00:57 INFO - Running setup.py install for moztest 14:00:57 INFO - Running setup.py install for mozversion 14:00:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:00:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:00:57 INFO - Cleaning up... 14:00:57 INFO - Return code: 0 14:00:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:00:57 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')]} 14:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:57 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')]} 14:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:00:57 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:00:57 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 14:00:57 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 14:00:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:00:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:57 INFO - 'HOME': '/Users/cltbld', 14:00:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:57 INFO - 'LOGNAME': 'cltbld', 14:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:57 INFO - 'MOZ_NO_REMOTE': '1', 14:00:57 INFO - 'NO_EM_RESTART': '1', 14:00:57 INFO - 'PAGER': '/bin/cat', 14:00:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:57 INFO - 'PWD': '/builds/slave/test', 14:00:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:57 INFO - 'SHELL': '/bin/bash', 14:00:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:00:57 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:00:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:57 INFO - 'USER': 'cltbld', 14:00:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:57 INFO - 'XPC_FLAGS': '0x0', 14:00:57 INFO - 'XPC_SERVICE_NAME': '0', 14:00:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-zOCaO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:00:58 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)) 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-EnYKqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:00:58 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)) 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-lSNUL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:00:58 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)) 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-KhMxmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:00:58 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)) 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-YQIOAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:00:58 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)) 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-f9F43p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:00:58 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)) 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-MSEeYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:00:58 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)) 14:00:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:00:58 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-abzzhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-7ZM9IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-2axmUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-CoKKIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-NcJcFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-UB0JLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-OSnS54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-k3DQKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-2jUaIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:00:59 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)) 14:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:00:59 INFO - Running setup.py (path:/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/pip-PANh87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 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)) 14:00:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:00:59 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 14:00:59 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 14:00:59 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 14:00:59 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 14:00:59 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 14:00:59 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 14:01:02 INFO - Downloading blessings-1.6.tar.gz 14:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:01:02 INFO - Installing collected packages: blessings 14:01:02 INFO - Running setup.py install for blessings 14:01:02 INFO - Successfully installed blessings 14:01:02 INFO - Cleaning up... 14:01:02 INFO - Return code: 0 14:01:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:01:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:01:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:01:03 INFO - Reading from file tmpfile_stdout 14:01:03 INFO - Current package versions: 14:01:03 INFO - blessings == 1.6 14:01:03 INFO - blobuploader == 1.2.4 14:01:03 INFO - docopt == 0.6.1 14:01:03 INFO - manifestparser == 1.1 14:01:03 INFO - mozInstall == 1.12 14:01:03 INFO - mozcrash == 0.17 14:01:03 INFO - mozdebug == 0.1 14:01:03 INFO - mozdevice == 0.48 14:01:03 INFO - mozfile == 1.2 14:01:03 INFO - mozhttpd == 0.7 14:01:03 INFO - mozinfo == 0.9 14:01:03 INFO - mozleak == 0.1 14:01:03 INFO - mozlog == 3.2 14:01:03 INFO - moznetwork == 0.27 14:01:03 INFO - mozprocess == 0.22 14:01:03 INFO - mozprofile == 0.28 14:01:03 INFO - mozrunner == 6.11 14:01:03 INFO - mozscreenshot == 0.1 14:01:03 INFO - mozsystemmonitor == 0.3 14:01:03 INFO - moztest == 0.7 14:01:03 INFO - mozversion == 1.4 14:01:03 INFO - psutil == 3.1.1 14:01:03 INFO - requests == 1.2.3 14:01:03 INFO - wsgiref == 0.1.2 14:01:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:03 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')]} 14:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:03 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')]} 14:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:03 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:01:03 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 14:01:03 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 14:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:03 INFO - 'HOME': '/Users/cltbld', 14:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:03 INFO - 'LOGNAME': 'cltbld', 14:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:03 INFO - 'MOZ_NO_REMOTE': '1', 14:01:03 INFO - 'NO_EM_RESTART': '1', 14:01:03 INFO - 'PAGER': '/bin/cat', 14:01:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:03 INFO - 'PWD': '/builds/slave/test', 14:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:03 INFO - 'SHELL': '/bin/bash', 14:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:01:03 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:03 INFO - 'USER': 'cltbld', 14:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:03 INFO - 'XPC_FLAGS': '0x0', 14:01:03 INFO - 'XPC_SERVICE_NAME': '0', 14:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 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)) 14:01:03 INFO - Cleaning up... 14:01:03 INFO - Return code: 0 14:01:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:01:03 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')]} 14:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:03 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')]} 14:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:03 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:01:03 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 14:01:03 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 14:01:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:01:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:03 INFO - 'HOME': '/Users/cltbld', 14:01:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:03 INFO - 'LOGNAME': 'cltbld', 14:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:03 INFO - 'MOZ_NO_REMOTE': '1', 14:01:03 INFO - 'NO_EM_RESTART': '1', 14:01:03 INFO - 'PAGER': '/bin/cat', 14:01:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:03 INFO - 'PWD': '/builds/slave/test', 14:01:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:03 INFO - 'SHELL': '/bin/bash', 14:01:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:01:03 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:01:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:03 INFO - 'USER': 'cltbld', 14:01:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:03 INFO - 'XPC_FLAGS': '0x0', 14:01:03 INFO - 'XPC_SERVICE_NAME': '0', 14:01:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:03 INFO - Downloading/unpacking jsonschema 14:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:01:06 INFO - Downloading/unpacking functools32 (from jsonschema) 14:01:06 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 14:01:06 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 14:01:06 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 14:01:06 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 14:01:06 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 14:01:06 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 14:01:06 INFO - Downloading functools32-3.2.3-2.tar.gz 14:01:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:01:06 INFO - warning: no files found matching '*.txt' 14:01:06 INFO - no previously-included directories found matching 'build' 14:01:06 INFO - no previously-included directories found matching 'dist' 14:01:06 INFO - no previously-included directories found matching '.git*' 14:01:06 INFO - Installing collected packages: jsonschema, functools32 14:01:06 INFO - Running setup.py install for functools32 14:01:06 INFO - warning: no files found matching '*.txt' 14:01:06 INFO - no previously-included directories found matching 'build' 14:01:06 INFO - no previously-included directories found matching 'dist' 14:01:06 INFO - no previously-included directories found matching '.git*' 14:01:06 INFO - Successfully installed jsonschema functools32 14:01:06 INFO - Cleaning up... 14:01:06 INFO - Return code: 0 14:01:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:01:06 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')]} 14:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:06 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')]} 14:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:06 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 0x1045d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1045d9570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99995be2e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, '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.baDQHObdI7/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.mdhokoK7kC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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 14:01:06 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 14:01:06 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 14:01:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:01:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:06 INFO - 'HOME': '/Users/cltbld', 14:01:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:06 INFO - 'LOGNAME': 'cltbld', 14:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:06 INFO - 'MOZ_NO_REMOTE': '1', 14:01:06 INFO - 'NO_EM_RESTART': '1', 14:01:06 INFO - 'PAGER': '/bin/cat', 14:01:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:06 INFO - 'PWD': '/builds/slave/test', 14:01:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:06 INFO - 'SHELL': '/bin/bash', 14:01:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:01:06 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:01:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:06 INFO - 'USER': 'cltbld', 14:01:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:06 INFO - 'XPC_FLAGS': '0x0', 14:01:06 INFO - 'XPC_SERVICE_NAME': '0', 14:01:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:07 INFO - Downloading/unpacking flake8 14:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:01:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:01:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:01:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:01:09 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 14:01:09 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 14:01:09 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 14:01:09 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 14:01:09 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 14:01:09 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 14:01:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:01:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:01:09 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 14:01:09 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 14:01:09 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 14:01:09 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 14:01:09 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 14:01:09 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 14:01:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:01:10 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 14:01:10 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 14:01:10 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 14:01:10 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 14:01:10 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 14:01:10 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 14:01:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:01:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:01:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:01:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:01:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:01:10 INFO - no previously-included directories found matching 'docs/_build' 14:01:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:01:10 INFO - Running setup.py install for pep8 14:01:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:01:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:01:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:01:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:01:10 INFO - no previously-included directories found matching 'docs/_build' 14:01:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:01:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:01:11 INFO - Cleaning up... 14:01:11 INFO - Return code: 0 14:01:11 INFO - Running post-action listener: _resource_record_post_action 14:01:11 INFO - Running post-action listener: _start_resource_monitoring 14:01:11 INFO - Starting resource monitoring. 14:01:11 INFO - ##### 14:01:11 INFO - ##### Finished create-virtualenv step (success) 14:01:11 INFO - ##### 14:01:11 INFO - ##### 14:01:11 INFO - ##### Running install step. 14:01:11 INFO - ##### 14:01:11 INFO - Running pre-action listener: _resource_record_pre_action 14:01:11 INFO - Running main action method: install 14:01:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:01:11 INFO - Reading from file tmpfile_stdout 14:01:11 INFO - Detecting whether we're running mozinstall >=1.0... 14:01:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:01:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:01:11 INFO - Reading from file tmpfile_stdout 14:01:11 INFO - Output received: 14:01:11 INFO - Usage: mozinstall [options] installer 14:01:11 INFO - Options: 14:01:11 INFO - -h, --help show this help message and exit 14:01:11 INFO - -d DEST, --destination=DEST 14:01:11 INFO - Directory to install application into. [default: 14:01:11 INFO - "/builds/slave/test"] 14:01:11 INFO - --app=APP Application being installed. [default: firefox] 14:01:11 INFO - mkdir: /builds/slave/test/build/application 14:01:11 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'] 14:01:11 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 14:01:45 INFO - Reading from file tmpfile_stdout 14:01:45 INFO - Output received: 14:01:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:01:45 INFO - Running post-action listener: _resource_record_post_action 14:01:45 INFO - ##### 14:01:45 INFO - ##### Finished install step (success) 14:01:45 INFO - ##### 14:01:45 INFO - ##### 14:01:45 INFO - ##### Running run-tests step. 14:01:45 INFO - ##### 14:01:45 INFO - Running pre-action listener: _resource_record_pre_action 14:01:45 INFO - Running main action method: run_tests 14:01:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:01:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:01:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:01:45 INFO - 2016-05-23 14:01:45.322 screenresolution[2500:37782] starting screenresolution argv=screenresolution get 14:01:45 INFO - 2016-05-23 14:01:45.325 screenresolution[2500:37782] Display 0: 1600x1200x32@60 14:01:45 INFO - 2016-05-23 14:01:45.330 screenresolution[2501:37784] starting screenresolution argv=screenresolution list 14:01:45 INFO - Available Modes on Display 0 14:01:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:01:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:01:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:01:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:01:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:01:45 INFO - Intel Iris: 14:01:45 INFO - Chipset Model: Intel Iris 14:01:45 INFO - Type: GPU 14:01:45 INFO - Bus: Built-In 14:01:45 INFO - VRAM (Dynamic, Max): 1536 MB 14:01:45 INFO - Vendor: Intel (0x8086) 14:01:45 INFO - Device ID: 0x0a2e 14:01:45 INFO - Revision ID: 0x0009 14:01:45 INFO - Displays: 14:01:45 INFO - Display: 14:01:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:01:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:01:45 INFO - Display Serial Number: 1600x1200 60 14:01:45 INFO - Main Display: Yes 14:01:45 INFO - Mirror: Off 14:01:45 INFO - Online: Yes 14:01:45 INFO - Rotation: Supported 14:01:45 INFO - Return code: 0 14:01:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:01:45 INFO - Python 2.7.3 14:01:45 INFO - Return code: 0 14:01:45 INFO - grabbing minidump binary from tooltool 14:01: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')]} 14:01:45 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 0x104477d30>, '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 0x10429bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10446eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:01:45 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 14:01:45 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 14:01:45 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 14:01:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:01:45 INFO - Return code: 0 14:01:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:01:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:01:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:01:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:01:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:01:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:01:46 INFO - Return code: 0 14:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0247 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.baDQHObdI7/Render', 14:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:46 INFO - 'HOME': '/Users/cltbld', 14:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:46 INFO - 'LOGNAME': 'cltbld', 14:01:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:01:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:46 INFO - 'MOZ_NO_REMOTE': '1', 14:01:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:01:46 INFO - 'NO_EM_RESTART': '1', 14:01:46 INFO - 'PAGER': '/bin/cat', 14:01:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:46 INFO - 'PWD': '/builds/slave/test', 14:01:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:46 INFO - 'SHELL': '/bin/bash', 14:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mdhokoK7kC/Listeners', 14:01:46 INFO - 'TMPDIR': '/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/', 14:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:46 INFO - 'USER': 'cltbld', 14:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:46 INFO - 'XPC_FLAGS': '0x0', 14:01:46 INFO - 'XPC_SERVICE_NAME': '0', 14:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:01:47 INFO - mozversion application_buildid: 20160523130801 14:01:47 INFO - mozversion application_changeset: ec97adea519ba55ac05a2af28534e0594b97f80e 14:01:47 INFO - mozversion application_display_name: Nightly 14:01:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:01:47 INFO - mozversion application_name: Firefox 14:01:47 INFO - mozversion application_remotingname: firefox 14:01:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:01:47 INFO - mozversion application_vendor: Mozilla 14:01:47 INFO - mozversion application_version: 49.0a1 14:01:47 INFO - mozversion platform_buildid: 20160523130801 14:01:47 INFO - mozversion platform_changeset: ec97adea519ba55ac05a2af28534e0594b97f80e 14:01:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:01:47 INFO - mozversion platform_version: 49.0a1 14:01:47 INFO - using testdate: 1464037307 14:01:47 INFO - actual date: 1464037307 14:01:47 INFO - starting webserver on 'localhost:55751' 14:01:47 INFO - SUITE-START | Running 2 tests 14:01:47 INFO - TEST-START | dromaeo_css 14:01:47 INFO - operating with platform_type : mac_ 14:01:47 INFO - Initialising browser for dromaeo_css test... 14:01:47 INFO - TEST-INFO | started process 2519 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmp1iKQiT/profile http://localhost:55751/getInfo.html) 14:01:49 INFO - PROCESS | 2519 | __metrics Screen width/height:1600/1200 14:01:49 INFO - PROCESS | 2519 | colorDepth:24 14:01:49 INFO - PROCESS | 2519 | Browser inner width/height: 1024/689 14:01:49 INFO - PROCESS | 2519 | __metrics 14:01:49 INFO - PROCESS | 2519 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:01:49 ERROR - PROCESS | 2519 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:01:50 INFO - TEST-INFO | 2519: exit 0 14:01:50 INFO - Browser initialized. 14:01:50 INFO - Running cycle 1/1 for dromaeo_css test... 14:01:50 INFO - TEST-INFO | started process 2521 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmp1iKQiT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:02:01 INFO - PROCESS | 2521 | RSS: Main: 238870528 14:02:01 INFO - PROCESS | 2521 | 14:02:53 INFO - PROCESS | 2521 | 1464037373256 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] 14:05:03 INFO - PROCESS | 2521 | Cycle 1(1): loaded http://localhost:55751/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55751/tests/dromaeo/cssquery-ext.html) 14:05:04 INFO - PROCESS | 2521 | RSS: Main: 358846464 14:05:04 INFO - PROCESS | 2521 | 14:08:07 INFO - PROCESS | 2521 | Cycle 1(1): loaded http://localhost:55751/tests/dromaeo/cssquery-ext.html (next: http://localhost:55751/tests/dromaeo/cssquery-jquery.html) 14:08:08 INFO - PROCESS | 2521 | RSS: Main: 366280704 14:08:08 INFO - PROCESS | 2521 | 14:11:10 INFO - PROCESS | 2521 | Cycle 1(1): loaded http://localhost:55751/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55751/tests/dromaeo/cssquery-mootools.html) 14:11:10 INFO - PROCESS | 2521 | RSS: Main: 368574464 14:11:10 INFO - PROCESS | 2521 | 14:14:13 INFO - PROCESS | 2521 | Cycle 1(1): loaded http://localhost:55751/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55751/tests/dromaeo/cssquery-prototype.html) 14:14:13 INFO - PROCESS | 2521 | RSS: Main: 367792128 14:14:13 INFO - PROCESS | 2521 | 14:17:17 INFO - PROCESS | 2521 | Cycle 1(1): loaded http://localhost:55751/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55751/tests/dromaeo/cssquery-yui.html) 14:17:17 INFO - PROCESS | 2521 | RSS: Main: 334319616 14:17:17 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | Cycle 1(1): loaded http://localhost:55751/tests/dromaeo/cssquery-yui.html (next: http://localhost:55751/tests/dromaeo/cssquery-dojo.html) 14:20:20 INFO - PROCESS | 2521 | RSS: Main: 402952192 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | __start_tp_report 14:20:20 INFO - PROCESS | 2521 | _x_x_mozilla_page_load 14:20:20 INFO - PROCESS | 2521 | _x_x_mozilla_page_load_details 14:20:20 INFO - PROCESS | 2521 | |i|pagename|runs| 14:20:20 INFO - PROCESS | 2521 | |0;dojo.html;3022.3350862810175;3278.754093442991;3435.089701229175;3460.0830779843345;3477.374072666922;5687.203791469197;5717.656940583563;5725;5810.941890581093;5812.244408226925;4943.728094954776;5654.46282603152;5784;5837.970810145944;5875.529957603392;5194.480551944813;5254.185601231815;5282.260483532308;5282.366116066078;5371.785128594852;5374.193870919355;5400.864978375553;5412.161115027177;5434.429384914586;5440.863978400532;5218.739063046833;5268.604854635918;5283.128283833163;5295.761690723907;5342.946570534284;5260.789568417259;5263.210518422229;5272.578193744491;5287;5326;4489.7979590918485;5227.26818245446;5233.685978841282;5242.161254199348;5370.409254981949;5516.862078448031;5740.483356497935;5772.2496075510335;5798.10129429939;5825.854353641151;6104.786332478387;6126.24286675468;6172.783952561639;6184.103305020747;6255.906161407583;6188.907166392508;6222.471089957359;6226.564140510166;6259.561830671855;6296;5300.337457817773;5915.024021036524;5924.644521328734;5933.199018132583;5934.258217722785;6047.15339852421;6221.96889015552;6245.6252624842655;6250.781222657185;6311.33730958252;6119.785807496716;6136.539759518054;6139.539534534928;6142.815715528542;6151.169592105098;6138.143176733788;6237.313895471494;6291.496680265568;6307.5269354798575;6319.115323854664;6155.661438620829;6217.564770466114;6220.657863817443;6238.968805155945;6322.114903913456;6298.590591611531;6310.8422289442215;6327.050942358591;6327.303996560375;6329.208848893888;4855;4936.234883593049;4981.277714731344;4990.72551009698;5032.3206367139965;3883.8446462141765;4446.666500012512;4472.552744725502;4480.126375356775;4487.349334346502;5760.6255593386295;5765;5769.07694768835;5815.709214539256;5821.155932389865;6121.84695382619;6216.160818289563;6280.995040793451;6329.430351268408;6330.01884707871;5675.631083979528;5777.884442311214;5782.161586570008;5792.652440853562;5807.419258074159;5440.836774896759;5452.59105567084;5461.699606521679;5467.644603100785;5476.315460567429;5720.170575266647;6203.31763506005;6266.436020758154;6361.332060133712;6379.808605741835;6271.717772700148;6335.271443784024;6337.144485494399;6337.366263373626;6350.4919606432295;6343.207099112611;6356.650384228912;6360;6364.522660800551;6413.134226879311;5186.740662966761;6035.78874739382;6110.25500820216;6260.906086408617;6261.624302541862;6307.369263073656;6327.367263273636;6328.525360597881;6333.493320534254;6336.986082226822;6141.170941922784;6147.047207682816;6165.260168779596;6170.7223174958135;6170.784022559189;5972.910406343822;6042.123892035543;6078.513718902389;6124.234470691164;6141.201643786279;4968.900621987467;5991.64050156992;6021.397860213943;6046.606970546901;6061.969690151521;6108;6128.264608247039;6132.440349940305;6141.508679305555;6143.56995010345;5779.508741756998;5882.588218824641;5921.970390148022;5936.079907614327;5982.252218472691 14:20:20 INFO - PROCESS | 2521 | |1;ext.html;505.3228673577408;511.4042140905845;522.6654940837865;543.5217009032057;566.2497191221631;31705;44092;45417.31874021892;45442.31836522446;48183.759081204546;46287.305690414585;46861.765691171495;46889.29666055012;46967.76516117423;47085;8764.386492945496;9639.421634701923;9798.706038818847;9801.656942007032;9807.607695692164;8712.303015758756;11666.300021998708;11887.346195959219;11913.821292680617;12095.455704493275;11885.881141188607;11904.226225295328;12002.63992080239;12005.459754311034;12013.459394327276;8706.912930870674;11716.062714982781;11770.941145294262;11841.407929603529;12010.579629713005;11852.822207666806;11946.701332466671;11959.94020029903;11973.94013029938;11991;42068.368974465404;42659.78670106661;42978.57021429777;43021.92445188895;43223.35164972528;3056.7401770849283;3192.712655860984;3212.1166679163184;3534.2578058607724;4063.370177622444;24489;30384.54423183654;30635.846820765975;31158.532622010687;31340.373192536248;2676.0366268143453;2685.771709404703;2734.2754170144926;2744.3550765570076;2800.621916041329;458666;458882;460558.6972065152;461633;462014;66932.66134677376;67671.64656706937;67711;67753;67873.66063169652;36524.81737591348;37308.81345593309;37593.436098458544;38776.418353724155;70501.64749176221;15433.691326173413;16908.323667053428;17175.57061073483;17305.913470432566;17522.29910803579;11978.544398398308;12239.877601224052;13040.021998350161;13174.670633234055;13309.467621295233;19963.20147194125;20048.89975550113;20317.49206269855;20513.89743051305;20552;7985.64064617082;8064.233897779702;8074.798130046795;8087.797805054921;8127.2279133482225;901.3375169250595;934.0705997532499;994.0705440413092;1019.50553981319;1030.7835354575473;29185.27036824054;30485.542716859265;30791.384172316877;31040.22399440032;31119.844400777853;1053.6048981631889;1069.0432063303456;1081.1566977757361;1160.8200728887036;1166.3351889422947;15181.316840742193;15431.84568154304;15706.450274240347;15707.900446968832;16271.632244373506;717378;723926;730692;732574;732845;63795.36204637987;65790.67104664446;65910.67044664746;65960;66181.33818661752;1313.3302015971924;1346.4748747988292;1425.2232533269187;1457.4898785425016;1476.29138013754;30108.955753273698;31274.374512509166;31557.36885262328;31932.6806731939;32327.191820204684;54350.184747228035;54572.72713636406;54757.45242547523;55347.44652553584;55374.723126385725;462177;468751;471431;474923;475979;24037.879810600836;45489.77255113703;45581.316280256484;46596.767016164704;47440.7627961858;47531;48018.5198148014;48194.75902620464;49241.768955776395;50388.99222015613;39113.217735645696;40577.391339130525;41211.1757764837;41601.37597936094;41889.162216754885;35121.1219719509;37859.81070094632;39883.61549269081;40642.59357406388;41346.58653413427;37769.433458498694;39258.803705981285;39324.01689957774;39393.40909886411;40373.798131009156;33749;34616.48075278923;34905.30189396147;35308.82345588256;35483.82258088693;24474;25623.871880640476;25882.223533294033;25885.482290354463;26019.349516262246 14:20:20 INFO - PROCESS | 2521 | |2;jquery.html;340.2463543251697;377.67708609139186;382.4951064594735;403.38282427885343;409.35730902483095;19418.320358787445;20505.794942050576;20523.179072837087;20560.485987850312;20669.689954650694;20499.38501844947;20610.38168854933;20688.06903689334;21273;21613.567728645456;5344.278522399484;5829.9708501457435;5888.9328507300015;6012.428819262163;6032;5843.532517398619;5995.370486098962;6027.849303767419;6043;6047.0627052806885;5814.302283725959;5842.766289348421;5939.07944268639;5949.375315591866;5981.521478281748;5236.607254455912;5762.279715035621;5927.436893495111;5947;5963.403659634002;5825.067989121762;5851.882962340772;5943.375945525744;5969.731362088716;6011.609245399035;13258.403371848193;13354.86645133546;13430.798538021938;13456.990725695612;13490.988175886847;2291.129370839087;2392.7966122879575;2434.2818868433856;2520.911768088108;2673.8930442782266;9584.28117891161;9673.177779888718;9832.459214743258;10134.290599658027;10146.340487868265;1694.9830501694948;1731.8787684862066;1743.171993303171;1772.158224843202;1801.1714611278828;120020;125065;128259.09797667117;128326.71673283241;128757;11352.978231959163;11492.195546311845;11568.710782230342;11602.825957610643;11683.240589361665;14536.854631453762;14634;14660;14678.63303417423;14689;8439.915600844035;8507.744767656981;8521.190486903733;8644.22202001823;8745.038045814956;8355;8373.95813020931;8470.618822153017;8474.576271186415;8647.740567782977;3963.9405408918888;4498.235299999008;4514.029483661028;4572.817087316536;4673.789679464432;1660.327567335207;1755.3154269834774;1920.3470819921015;1946.4841816918529;1993.3056901633786;876.1151237250295;878.1789027259533;957.2820384711466;990.3315262197988;1014.1481155829139;19391.90304048499;19780.70328945067;20346.389608311776;20941.476463088544;21273.36179914605;918.1644703319947;954.3748844506721;962.3648392061206;964.4984608003939;1019.9694009179736;10178.745531361626;10391.53238104287;10836.891631083747;10901.563937442414;10998.560057597766;590508;601035;608017.9198207961;608379;615232;76122.6193869027;76476.23523764803;88216;122427.55144897052;137922;945.3193700535741;945.3382632157517;972.8832540095234;980.4264505264357;983.4443539400359;18381.448556543324;18412.723809142604;18436.907815460836;18437.26250949947;18452.81547184511;52540;53353.19970200528;56207.594810130075;56702.43297566971;56720;73073;75860.24139758752;77873;79753;82923.7807164453;7797.7660670179985;8014.959925200336;8036.517808931483;8233.34133269349;8304.418690691593;12302.569410070604;12503.374831258583;12716.491340346283;12789.36053197355;13045.543405980745;8430.536320502431;9667.743193384815;10674.306170098918;10687.732806679895;11182;10618.36289822613;10667.146628269875;10722.24944253926;10942.398168100832;11081.168912331768;10826.621068262573;11021.44892755343;11220.326780393203;11257.380844053656;11424.314541127558;7930.682772689028;7934.087579928382;8174.836503269782;8190.099089100313;8231.753047408587;8062.355011598941;9880.703578892644;11018.779624407627;11285.887141128484;11286.322820630789 14:20:20 INFO - PROCESS | 2521 | |3;mootools.html;1427.4504315838399;1589.4278059898434;1600.4958438092;1861.9162137703802;1998.0808827939156;7864.724734634289;8501.979762428517;8638.352123590732;8897.065808090147;8927.508987005713;8296.626651800669;8758.08040155784;8769.210771030623;8776.341774366916;8784.868226976601;2102.1381233694187;2194.36363454598;2329.732080810703;2352.1414683640432;2388.6417037444435;2409.759024097585;2430.1980346485616;2432.3554258121612;2435.8538487690707;2435.963460548084;2358.2099996501192;2400.95198096038;2409.650600662903;2412.107520217522;2417.0452671194957;2080.3342930262247;2298.10373954158;2359.575276450238;2376.869272190029;2383.642453631952;2393.365758074112;2412.9517409651708;2419.5040016796515;2420.854748715065;2423.9515209695883;8835;8871.689490867853;8909.821803563893;8913.331500137516;8921.286297096216;5251.133562962106;5284.233786100994;5346.0109879672;5404.243405923174;5419.295491586068;3381.4589665653625;3463.445848664227;3518.8592456301717;3526.612072672004;3587.820608969541;1043.546057465005;1580.170410534467;1627.9430219942244;1636.5990332368647;1647.4069335039376;11740.295582265091;11793.705157371049;11826.467808948619;11863.16957812962;11947.223430477077;4509.842155524541;4667.929981050287;4716.575508204278;4721.95278047222;4741.976290118528;9228.169464748205;9269.16577508014;9278.025807290138;9340.365436048663;9355.48544830041;5046.1169295373165;5280.551153152025;5315.441878602768;5318.25544423781;5345.599080068932;4415.889602759892;4583.839565615188;4663.48701642819;4808.639352048541;4832.081904438145;3754.7934863582764;3813.809309534512;3843.6348546888003;3877.9806100969877;3900.1029763154065;1165.9825102623297;1172.7888979983513;1172.7947609168361;1184.0705046538599;1187.0088476122535;845.6575087089739;854.7433779776746;887.5473508510706;897.869808877709;941.5904081724335;5641.830745077572;5697.6296540724725;5825.970870145707;5856.619319744288;5898.469137777534;896.5382827843666;925.2875286029854;927.2442958988403;929.7861491856776;948.6821914658667;3455.8963231103107;3566.8051202847337;3782.8273235296597;3794.2790869734663;3860.8841734748003;9916.80166396668;10966.725831854108;11056;11059.059979901638;11089.445527723743;10647.828738838722;10793;11007.559697612005;11017.173711971634;11040.061594764375;8161.510309381456;8333.999920009517;8434.072252052269;8436.746897593082;8447.197516235949;5600.523955463668;5675.858103547444;5721.056025755704;5773.740181691918;5774.82675519735;8689.8696519551;9932.553035113582;10290.12533934624;10298.124659403735;10304.021117993778;8570.871436928326;9939.304248702521;10060;10502.737431564272;10543.209259305737;5758.625689330348;6355.491560675227;6581.308962558863;6683.598984060777;6888.552244104118;8341.916580834113;8455.915440845758;8475.110113438;8529.232369086563;8552.57237138153;7310.59791711461;7416.6291685414435;7524.209957954555;7659.502132361378;7680.923190768244;7256.947742577402;7372.373348265458;7380.446466514925;7483.289087536676;7526.548407095592;6161.815145545641;7089.220185779664;7099.538529995534;7131.536450130725;7135.357817796424;4785.95214047869;4810.13417584838;4914.140025495595;4950.331705219894;5101.20931255656;4933.531314525115;5004.674696144739;5016.08293416447;5059.721715305546;5242.9475705243985 14:20:20 INFO - PROCESS | 2521 | |4;prototype.html;304.8597645083262;308.2355757720852;318.12198332602026;324.77590462580804;327.53980657176675;5002.574781143602;5033.094043072246;5401.78392864285;5409.9459005409935;5420.159875219338;4281.678874084449;4527.071950250199;5100.719460429675;5120.874836942664;5235.162374020158;3627.877691550433;4078.084169818579;4090.668319058138;4114.259433302004;4142.860713303843;3543.4330507118866;3826;3828.7747920665397;4093.4678491796026;4125.566815484376;3449.8275086245712;3882.844686212548;4093.8362465501345;4127.298359278915;4144.751314921099;3766.8493260269693;4029.516458025041;4056.249593825152;4069.1251380953236;4072.490938632671;3553.5125412211296;3560.928781424357;3566.696830769388;3568.375534281516;3570.2145527983803;4190.979045104786;4633.86098417048;4928.937120717948;4936.185529387682;5114.923276150861;4614.954130274003;4666.300054991744;4673.462551806551;4683.882902927454;4715.836159405446;4601.470830854428;5583.58123140762;5588.748506317185;5650.6892120933335;5730.226419433365;3259.429599820022;3807.058400215882;3938.4683067786054;3946.9013274668077;3949.269385163754;5890.617109887887;5913.438223368816;5936.278479241015;5941.762329506814;5965.3736357682255;3924.823382947774;3999.8800035998966;4015.5382131055303;4025.4163146343617;4049.7445791804635;3929.1159489115294;3949.1311911379453;4100.282450571198;4104.774237416971;4122.154958233555;3971.4638523799495;4249.76756740548;4638.675292729544;4673.392458980311;4677.859664210011;5085.923711144335;5624.440879399864;5636.830895073154;5660.235868157828;5706.9727449059565;1067.6743593204146;1203.4825025239231;1228.8648248692637;1338.9464421423033;1380.1029330934973;881.722257488889;888.7866911941217;911.3757076402685;937.9155875971195;951.9666811661558;4623.352730617717;5172.637915345965;5192.714400707921;5241.895162096736;5285.788568457218;4663.626909847204;5089.3638295213095;5282.207668849626;5315.415304316522;5718.313802343745;4965.553100220997;5293.935365207572;5302.8333766571295;5323.121684922022;5369.858632699914;2615.3853844346563;2653.8057873956795;2980.463516566995;2985.7312841844337;3044.9647119979313;4781.880452988703;5467.671939683552;5521.785207254397;5822.592418530662;6164.722587483574;5391.568674506108;6031.07005758162;6079.361667024988;6095.603785753912;6097.298810636745;5551.3893471718075;5901.554119240727;6161.907571386433;6168.259808822791;6184.381561843779;4903;5260.421353651172;5631.380548139619;5858.794942177174;5919;5582.134769110794;5797.536197104306;5838.824835254949;5852.561057920758;5855.130985817004;5928.733207005609;6055.969720151371;6143.095640351506;6230.504678876946;6246.377101281337;3519.560054993126;3777.395616701315;3891.416287556889;3905.336092864163;3960.5643379228836;5049.444561098349;5841.415858414124;5937.732802024006;5982.46157845796;6001.939980600313;5227.784912278997;5287.074761916572;5308.230306605444;5587.497125258747;5786.132080188006;4827.275908613736;5216.269722238813;5308.362996440452;5364.2490051393615;5651.463111004448;4493.6854420190275;5445.529706979014;5492.395836457907;5613.887722245614;5623.2408624835125;4635;5041.899162016666;5663.932664577482;5698.572607054571;5703.515201207943;4699.177643912233;4963.602911766978;5361.490658387447;5565.6104072714525;5567.331920169606 14:20:20 INFO - PROCESS | 2521 | |5;yui.html;606.7724603273772;676.452073820206;736.6243215959859;746.8954346391506;752.3379426105026;13824.170549767021;15011.099334039951;15303.15832629205;15333.233338333066;15342.769858452104;12125.211861229036;13742.175469471838;13794.44822207113;13822.930885345559;13846.861531384684;752.3229093815569;913.7441516375392;947.052947052947;951.5860600638745;956.3974695941547;887.2946007923716;904.0597778310549;934.22926085979;940.3323640215456;952.2477242978039;771.5409331447808;867.1718508824058;1006.7986402719449;1007.5566750629736;1012.1143999000874;943.4386540008718;982.6315131825565;985.3348989432104;989.6635144050986;989.7723523589578;689.8965155226706;701.6000879498688;743.0117943135616;743.1750756660103;744.9292317229855;14536.200508972002;14608.853911460856;14642.267886605627;14673.853261467355;14678.486252981202;12176.147669663127;12272.32502212378;12278.447469863877;12308.26150430977;12414.937925310316;9111.17999380059;10691.572337106507;10790.406527640976;10854.077403420719;11173.776524469466;4008.939865902014;4031.717779755418;4039.1719697462245;4098.631123198897;4191.91616167675;70267.2973270266;70436;71543.28456715419;71618.64190679065;72415.63792181111;8303.501789892625;9296.767580810398;9314.580843862042;9398.765030874147;9569.808603827885;20494.18023279082;21236.78763212379;21245.150193992067;21248;21386;7292.45306602007;7317.231690672509;7414.851702966019;7473.40212782987;7482.775516734507;3876.5348158221195;3942.56631770505;3999.060220848098;4046.4537287465814;4053.5135783706146;3279.8524066417067;3305.4380755271663;3308.156420112856;3368.814715190639;3381.9492707609406;486.5839707050492;539.9595030372659;599.0534954771538;618.1994317359031;631.5957787016313;485.9562639362474;584.7047241143196;591.7396345608005;614.443928244939;614.5022531749297;8005.759827205193;8191.139930307351;8407.159284071666;8516.574171291411;8517.190866867637;469.77920377422555;583.439897698206;610.2463457629824;610.5072553394592;630.7762939896586;3822.5412950446125;3970.7419017763755;3996.3805610130476;4011.939820902689;4065.7560546367313;99868;100879;101441.71165766875;101496;101965;56998.43001570014;74151;81087;88596.6710499343;89177.55411222903;77397;77954;78944.72186679934;79449;79937.60031199922;7780.766577002699;7860.449768516149;7872.685092596232;7925.564093974886;8038.718644847636;47766.761166193945;47972.520274798204;48317.75841120772;49613.25580116373;49966.50033499764;63436.414089646285;64556.6772166155;64777;65206;66816;10242.948785256027;10250.897491024994;10675.039246467855;11263.155263355446;11317.71705707364;12208.511659533518;12252.632421027383;12414.751704966031;12429.192102513307;12592.433340499883;11174.49714762854;11687.590934317259;11708.414579270831;11894.840972451337;12058.698532536757;10578.047975682226;10817.837732434176;11589.362585057903;11620.825687614524;11890.881091188978;11324.94337528307;11440.02759765396;11442.542298307975;11842.585509507126;11859.584914528295;9731.708048758548;9758.463284519135;9856.753581160528;10014.799704005733;10100.646477373257;8684.914385701788;8745.081766414434;9057.68298109563;10048.54781534818;10104.343217690826 14:20:20 INFO - PROCESS | 2521 | __end_tp_report 14:20:20 INFO - PROCESS | 2521 | __start_cc_report 14:20:20 INFO - PROCESS | 2521 | _x_x_mozilla_cycle_collect,1175 14:20:20 INFO - PROCESS | 2521 | __end_cc_report 14:20:20 INFO - PROCESS | 2521 | __startTimestamp1464038420926__endTimestamp 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | ------- Summary: start ------- 14:20:20 INFO - PROCESS | 2521 | Number of tests: 6 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | [#0] dojo.html Cycles:165 Average:5757.94 Median:5972.91 stddev:655.74 (11.0%) stddev-sans-first:621.64 14:20:20 INFO - PROCESS | 2521 | Values: 3022.3 3278.8 3435.1 3460.1 3477.4 5687.2 5717.7 5725.0 5810.9 5812.2 4943.7 5654.5 5784.0 5838.0 5875.5 5194.5 5254.2 5282.3 5282.4 5371.8 5374.2 5400.9 5412.2 5434.4 5440.9 5218.7 5268.6 5283.1 5295.8 5342.9 5260.8 5263.2 5272.6 5287.0 5326.0 4489.8 5227.3 5233.7 5242.2 5370.4 5516.9 5740.5 5772.2 5798.1 5825.9 6104.8 6126.2 6172.8 6184.1 6255.9 6188.9 6222.5 6226.6 6259.6 6296.0 5300.3 5915.0 5924.6 5933.2 5934.3 6047.2 6222.0 6245.6 6250.8 6311.3 6119.8 6136.5 6139.5 6142.8 6151.2 6138.1 6237.3 6291.5 6307.5 6319.1 6155.7 6217.6 6220.7 6239.0 6322.1 6298.6 6310.8 6327.1 6327.3 6329.2 4855.0 4936.2 4981.3 4990.7 5032.3 3883.8 4446.7 4472.6 4480.1 4487.3 5760.6 5765.0 5769.1 5815.7 5821.2 6121.8 6216.2 6281.0 6329.4 6330.0 5675.6 5777.9 5782.2 5792.7 5807.4 5440.8 5452.6 5461.7 5467.6 5476.3 5720.2 6203.3 6266.4 6361.3 6379.8 6271.7 6335.3 6337.1 6337.4 6350.5 6343.2 6356.7 6360.0 6364.5 6413.1 5186.7 6035.8 6110.3 6260.9 6261.6 6307.4 6327.4 6328.5 6333.5 6337.0 6141.2 6147.0 6165.3 6170.7 6170.8 5972.9 6042.1 6078.5 6124.2 6141.2 4968.9 5991.6 6021.4 6046.6 6062.0 6108.0 6128.3 6132.4 6141.5 6143.6 5779.5 5882.6 5922.0 5936.1 5982.3 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | [#1] ext.html Cycles:180 Average:70123.55 Median:37814.62 stddev:151439.97 (400.5%) stddev-sans-first:151774.59 14:20:20 INFO - PROCESS | 2521 | Values: 505.3 511.4 522.7 543.5 566.2 31705.0 44092.0 45417.3 45442.3 48183.8 46287.3 46861.8 46889.3 46967.8 47085.0 8764.4 9639.4 9798.7 9801.7 9807.6 8712.3 11666.3 11887.3 11913.8 12095.5 11885.9 11904.2 12002.6 12005.5 12013.5 8706.9 11716.1 11770.9 11841.4 12010.6 11852.8 11946.7 11959.9 11973.9 11991.0 42068.4 42659.8 42978.6 43021.9 43223.4 3056.7 3192.7 3212.1 3534.3 4063.4 24489.0 30384.5 30635.8 31158.5 31340.4 2676.0 2685.8 2734.3 2744.4 2800.6 458666.0 458882.0 460558.7 461633.0 462014.0 66932.7 67671.6 67711.0 67753.0 67873.7 36524.8 37308.8 37593.4 38776.4 70501.6 15433.7 16908.3 17175.6 17305.9 17522.3 11978.5 12239.9 13040.0 13174.7 13309.5 19963.2 20048.9 20317.5 20513.9 20552.0 7985.6 8064.2 8074.8 8087.8 8127.2 901.3 934.1 994.1 1019.5 1030.8 29185.3 30485.5 30791.4 31040.2 31119.8 1053.6 1069.0 1081.2 1160.8 1166.3 15181.3 15431.8 15706.5 15707.9 16271.6 717378.0 723926.0 730692.0 732574.0 732845.0 63795.4 65790.7 65910.7 65960.0 66181.3 1313.3 1346.5 1425.2 1457.5 1476.3 30109.0 31274.4 31557.4 31932.7 32327.2 54350.2 54572.7 54757.5 55347.4 55374.7 462177.0 468751.0 471431.0 474923.0 475979.0 24037.9 45489.8 45581.3 46596.8 47440.8 47531.0 48018.5 48194.8 49241.8 50389.0 39113.2 40577.4 41211.2 41601.4 41889.2 35121.1 37859.8 39883.6 40642.6 41346.6 37769.4 39258.8 39324.0 39393.4 40373.8 33749.0 34616.5 34905.3 35308.8 35483.8 24474.0 25623.9 25882.2 25885.5 26019.3 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | [#2] jquery.html Cycles:180 Average:34320.89 Median:2183.66 stddev:100613.23 (4607.5%) stddev-sans-first:100863.13 14:20:20 INFO - PROCESS | 2521 | Values: 340.2 377.7 382.5 403.4 409.4 19418.3 20505.8 20523.2 20560.5 20669.7 20499.4 20610.4 20688.1 21273.0 21613.6 5344.3 5830.0 5888.9 6012.4 6032.0 5843.5 5995.4 6027.8 6043.0 6047.1 5814.3 5842.8 5939.1 5949.4 5981.5 5236.6 5762.3 5927.4 5947.0 5963.4 5825.1 5851.9 5943.4 5969.7 6011.6 13258.4 13354.9 13430.8 13457.0 13491.0 2291.1 2392.8 2434.3 2520.9 2673.9 9584.3 9673.2 9832.5 10134.3 10146.3 1695.0 1731.9 1743.2 1772.2 1801.2 120020.0 125065.0 128259.1 128326.7 128757.0 11353.0 11492.2 11568.7 11602.8 11683.2 14536.9 14634.0 14660.0 14678.6 14689.0 8439.9 8507.7 8521.2 8644.2 8745.0 8355.0 8374.0 8470.6 8474.6 8647.7 3963.9 4498.2 4514.0 4572.8 4673.8 1660.3 1755.3 1920.3 1946.5 1993.3 876.1 878.2 957.3 990.3 1014.1 19391.9 19780.7 20346.4 20941.5 21273.4 918.2 954.4 962.4 964.5 1020.0 10178.7 10391.5 10836.9 10901.6 10998.6 590508.0 601035.0 608017.9 608379.0 615232.0 76122.6 76476.2 88216.0 122427.6 137922.0 945.3 945.3 972.9 980.4 983.4 18381.4 18412.7 18436.9 18437.3 18452.8 52540.0 53353.2 56207.6 56702.4 56720.0 73073.0 75860.2 77873.0 79753.0 82923.8 7797.8 8015.0 8036.5 8233.3 8304.4 12302.6 12503.4 12716.5 12789.4 13045.5 8430.5 9667.7 10674.3 10687.7 11182.0 10618.4 10667.1 10722.2 10942.4 11081.2 10826.6 11021.4 11220.3 11257.4 11424.3 7930.7 7934.1 8174.8 8190.1 8231.8 8062.4 9880.7 11018.8 11285.9 11286.3 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | [#3] mootools.html Cycles:180 Average:5676.74 Median:5010.38 stddev:3184.64 (63.6%) stddev-sans-first:3177.56 14:20:20 INFO - PROCESS | 2521 | Values: 1427.5 1589.4 1600.5 1861.9 1998.1 7864.7 8502.0 8638.4 8897.1 8927.5 8296.6 8758.1 8769.2 8776.3 8784.9 2102.1 2194.4 2329.7 2352.1 2388.6 2409.8 2430.2 2432.4 2435.9 2436.0 2358.2 2401.0 2409.7 2412.1 2417.0 2080.3 2298.1 2359.6 2376.9 2383.6 2393.4 2413.0 2419.5 2420.9 2424.0 8835.0 8871.7 8909.8 8913.3 8921.3 5251.1 5284.2 5346.0 5404.2 5419.3 3381.5 3463.4 3518.9 3526.6 3587.8 1043.5 1580.2 1627.9 1636.6 1647.4 11740.3 11793.7 11826.5 11863.2 11947.2 4509.8 4667.9 4716.6 4722.0 4742.0 9228.2 9269.2 9278.0 9340.4 9355.5 5046.1 5280.6 5315.4 5318.3 5345.6 4415.9 4583.8 4663.5 4808.6 4832.1 3754.8 3813.8 3843.6 3878.0 3900.1 1166.0 1172.8 1172.8 1184.1 1187.0 845.7 854.7 887.5 897.9 941.6 5641.8 5697.6 5826.0 5856.6 5898.5 896.5 925.3 927.2 929.8 948.7 3455.9 3566.8 3782.8 3794.3 3860.9 9916.8 10966.7 11056.0 11059.1 11089.4 10647.8 10793.0 11007.6 11017.2 11040.1 8161.5 8334.0 8434.1 8436.7 8447.2 5600.5 5675.9 5721.1 5773.7 5774.8 8689.9 9932.6 10290.1 10298.1 10304.0 8570.9 9939.3 10060.0 10502.7 10543.2 5758.6 6355.5 6581.3 6683.6 6888.6 8341.9 8455.9 8475.1 8529.2 8552.6 7310.6 7416.6 7524.2 7659.5 7680.9 7256.9 7372.4 7380.4 7483.3 7526.5 6161.8 7089.2 7099.5 7131.5 7135.4 4786.0 4810.1 4914.1 4950.3 5101.2 4933.5 5004.7 5016.1 5059.7 5242.9 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | [#4] prototype.html Cycles:180 Average:4558.59 Median:5067.68 stddev:1412.05 (27.9%) stddev-sans-first:1379.45 14:20:20 INFO - PROCESS | 2521 | Values: 304.9 308.2 318.1 324.8 327.5 5002.6 5033.1 5401.8 5409.9 5420.2 4281.7 4527.1 5100.7 5120.9 5235.2 3627.9 4078.1 4090.7 4114.3 4142.9 3543.4 3826.0 3828.8 4093.5 4125.6 3449.8 3882.8 4093.8 4127.3 4144.8 3766.8 4029.5 4056.2 4069.1 4072.5 3553.5 3560.9 3566.7 3568.4 3570.2 4191.0 4633.9 4928.9 4936.2 5114.9 4615.0 4666.3 4673.5 4683.9 4715.8 4601.5 5583.6 5588.7 5650.7 5730.2 3259.4 3807.1 3938.5 3946.9 3949.3 5890.6 5913.4 5936.3 5941.8 5965.4 3924.8 3999.9 4015.5 4025.4 4049.7 3929.1 3949.1 4100.3 4104.8 4122.2 3971.5 4249.8 4638.7 4673.4 4677.9 5085.9 5624.4 5636.8 5660.2 5707.0 1067.7 1203.5 1228.9 1338.9 1380.1 881.7 888.8 911.4 937.9 952.0 4623.4 5172.6 5192.7 5241.9 5285.8 4663.6 5089.4 5282.2 5315.4 5718.3 4965.6 5293.9 5302.8 5323.1 5369.9 2615.4 2653.8 2980.5 2985.7 3045.0 4781.9 5467.7 5521.8 5822.6 6164.7 5391.6 6031.1 6079.4 6095.6 6097.3 5551.4 5901.6 6161.9 6168.3 6184.4 4903.0 5260.4 5631.4 5858.8 5919.0 5582.1 5797.5 5838.8 5852.6 5855.1 5928.7 6056.0 6143.1 6230.5 6246.4 3519.6 3777.4 3891.4 3905.3 3960.6 5049.4 5841.4 5937.7 5982.5 6001.9 5227.8 5287.1 5308.2 5587.5 5786.1 4827.3 5216.3 5308.4 5364.2 5651.5 4493.7 5445.5 5492.4 5613.9 5623.2 4635.0 5041.9 5663.9 5698.6 5703.5 4699.2 4963.6 5361.5 5565.6 5567.3 14:20:20 INFO - PROCESS | 2521 | 14:20:20 INFO - PROCESS | 2521 | [#5] yui.html Cycles:180 Average:18383.27 Median:25049.92 stddev:26255.55 (104.8%) stddev-sans-first:26295.27 14:20:20 INFO - PROCESS | 2521 | Values: 606.8 676.5 736.6 746.9 752.3 13824.2 15011.1 15303.2 15333.2 15342.8 12125.2 13742.2 13794.4 13822.9 13846.9 752.3 913.7 947.1 951.6 956.4 887.3 904.1 934.2 940.3 952.2 771.5 867.2 1006.8 1007.6 1012.1 943.4 982.6 985.3 989.7 989.8 689.9 701.6 743.0 743.2 744.9 14536.2 14608.9 14642.3 14673.9 14678.5 12176.1 12272.3 12278.4 12308.3 12414.9 9111.2 10691.6 10790.4 10854.1 11173.8 4008.9 4031.7 4039.2 4098.6 4191.9 70267.3 70436.0 71543.3 71618.6 72415.6 8303.5 9296.8 9314.6 9398.8 9569.8 20494.2 21236.8 21245.2 21248.0 21386.0 7292.5 7317.2 7414.9 7473.4 7482.8 3876.5 3942.6 3999.1 4046.5 4053.5 3279.9 3305.4 3308.2 3368.8 3381.9 486.6 540.0 599.1 618.2 631.6 486.0 584.7 591.7 614.4 614.5 8005.8 8191.1 8407.2 8516.6 8517.2 469.8 583.4 610.2 610.5 630.8 3822.5 3970.7 3996.4 4011.9 4065.8 99868.0 100879.0 101441.7 101496.0 101965.0 56998.4 74151.0 81087.0 88596.7 89177.6 77397.0 77954.0 78944.7 79449.0 79937.6 7780.8 7860.4 7872.7 7925.6 8038.7 47766.8 47972.5 48317.8 49613.3 49966.5 63436.4 64556.7 64777.0 65206.0 66816.0 10242.9 10250.9 10675.0 11263.2 11317.7 12208.5 12252.6 12414.8 12429.2 12592.4 11174.5 11687.6 11708.4 11894.8 12058.7 10578.0 10817.8 11589.4 11620.8 11890.9 11324.9 11440.0 11442.5 11842.6 11859.6 9731.7 9758.5 9856.8 10014.8 10100.6 8684.9 8745.1 9057.7 10048.5 10104.3 14:20:20 INFO - PROCESS | 2521 | -------- Summary: end -------- 14:20:20 INFO - PROCESS | 2521 | 14:20:21 INFO - TEST-INFO | 2521: exit 0 14:20:21 INFO - TEST-OK | dromaeo_css | took 1114450ms 14:20:21 INFO - TEST-START | kraken 14:20:21 INFO - operating with platform_type : mac_ 14:20:21 INFO - Initialising browser for kraken test... 14:20:21 INFO - TEST-INFO | started process 2523 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpdgH44X/profile http://localhost:55751/getInfo.html) 14:20:23 INFO - PROCESS | 2523 | __metrics Screen width/height:1600/1200 14:20:23 INFO - PROCESS | 2523 | colorDepth:24 14:20:23 INFO - PROCESS | 2523 | Browser inner width/height: 1024/689 14:20:23 INFO - PROCESS | 2523 | __metrics 14:20:23 INFO - PROCESS | 2523 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:20:23 ERROR - PROCESS | 2523 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:20:24 INFO - TEST-INFO | 2523: exit 0 14:20:24 INFO - Browser initialized. 14:20:24 INFO - Running cycle 1/1 for kraken test... 14:20:24 INFO - TEST-INFO | started process 2525 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/tmpdgH44X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:36 INFO - PROCESS | 2525 | RSS: Main: 238759936 14:20:36 INFO - PROCESS | 2525 | 14:20:39 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55751/tests/kraken/driver.html?testName=audio-beat-detection) 14:20:39 INFO - PROCESS | 2525 | RSS: Main: 287375360 14:20:39 INFO - PROCESS | 2525 | 14:20:42 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55751/tests/kraken/driver.html?testName=audio-dft) 14:20:43 INFO - PROCESS | 2525 | RSS: Main: 296050688 14:20:43 INFO - PROCESS | 2525 | 14:20:46 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55751/tests/kraken/driver.html?testName=audio-fft) 14:20:47 INFO - PROCESS | 2525 | RSS: Main: 386461696 14:20:47 INFO - PROCESS | 2525 | 14:20:49 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55751/tests/kraken/driver.html?testName=audio-oscillator) 14:20:50 INFO - PROCESS | 2525 | RSS: Main: 284057600 14:20:50 INFO - PROCESS | 2525 | 14:20:52 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55751/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:20:52 INFO - PROCESS | 2525 | RSS: Main: 288186368 14:20:52 INFO - PROCESS | 2525 | 14:20:55 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55751/tests/kraken/driver.html?testName=imaging-darkroom) 14:20:56 INFO - PROCESS | 2525 | RSS: Main: 327299072 14:20:56 INFO - PROCESS | 2525 | 14:20:59 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55751/tests/kraken/driver.html?testName=imaging-desaturate) 14:21:00 INFO - PROCESS | 2525 | RSS: Main: 329629696 14:21:00 INFO - PROCESS | 2525 | 14:21:03 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55751/tests/kraken/driver.html?testName=json-parse-financial) 14:21:03 INFO - PROCESS | 2525 | RSS: Main: 328544256 14:21:03 INFO - PROCESS | 2525 | 14:21:05 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55751/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:21:06 INFO - PROCESS | 2525 | RSS: Main: 316346368 14:21:06 INFO - PROCESS | 2525 | 14:21:07 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:21:08 INFO - PROCESS | 2525 | RSS: Main: 323592192 14:21:08 INFO - PROCESS | 2525 | 14:21:10 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:21:11 INFO - PROCESS | 2525 | RSS: Main: 325799936 14:21:11 INFO - PROCESS | 2525 | 14:21:14 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:21:15 INFO - PROCESS | 2525 | RSS: Main: 330534912 14:21:15 INFO - PROCESS | 2525 | 14:21:18 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:21:18 INFO - PROCESS | 2525 | RSS: Main: 328511488 14:21:18 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:55751/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55751/tests/kraken/driver.html?testName=ai-astar) 14:21:20 INFO - PROCESS | 2525 | RSS: Main: 375275520 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | __start_tp_report 14:21:20 INFO - PROCESS | 2525 | _x_x_mozilla_page_load 14:21:20 INFO - PROCESS | 2525 | _x_x_mozilla_page_load_details 14:21:20 INFO - PROCESS | 2525 | |i|pagename|runs| 14:21:20 INFO - PROCESS | 2525 | |0;ai-astar;110;106;112;111;112;111;111;111;110;110 14:21:20 INFO - PROCESS | 2525 | |1;audio-beat-detection;153;117;117;202;118;116;141;112;152;116 14:21:20 INFO - PROCESS | 2525 | |2;audio-dft;185;185;183;183;183;178;183;182;183;182 14:21:20 INFO - PROCESS | 2525 | |3;audio-fft;76;57;59;116;56;55;59;64;122;59 14:21:20 INFO - PROCESS | 2525 | |4;audio-oscillator;95;75;141;79;86;75;102;79;76;109 14:21:20 INFO - PROCESS | 2525 | |5;imaging-gaussian-blur;85;84;86;86;86;83;87;86;91;84 14:21:20 INFO - PROCESS | 2525 | |6;imaging-darkroom;92;93;93;94;92;93;91;92;91;92 14:21:20 INFO - PROCESS | 2525 | |7;imaging-desaturate;83;83;84;83;83;84;84;86;85;83 14:21:20 INFO - PROCESS | 2525 | |8;json-parse-financial;56;58;58;57;58;59;57;117;56;55 14:21:20 INFO - PROCESS | 2525 | |9;json-stringify-tinderbox;64;71;67;76;76;72;70;69;70;69 14:21:20 INFO - PROCESS | 2525 | |10;stanford-crypto-aes;84;85;86;85;82;84;84;85;85;83 14:21:20 INFO - PROCESS | 2525 | |11;stanford-crypto-ccm;137;149;140;145;135;136;134;132;234;151 14:21:20 INFO - PROCESS | 2525 | |12;stanford-crypto-pbkdf2;162;165;162;165;169;208;214;161;162;161 14:21:20 INFO - PROCESS | 2525 | |13;stanford-crypto-sha256-iterative;65;66;66;67;66;64;65;65;65;65 14:21:20 INFO - PROCESS | 2525 | __end_tp_report 14:21:20 INFO - PROCESS | 2525 | __start_cc_report 14:21:20 INFO - PROCESS | 2525 | _x_x_mozilla_cycle_collect,2214 14:21:20 INFO - PROCESS | 2525 | __end_cc_report 14:21:20 INFO - PROCESS | 2525 | __startTimestamp1464038480836__endTimestamp 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | ------- Summary: start ------- 14:21:20 INFO - PROCESS | 2525 | Number of tests: 14 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#0] ai-astar Cycles:10 Average:110.40 Median:111.00 stddev:1.71 (1.5%) stddev-sans-first:1.81 14:21:20 INFO - PROCESS | 2525 | Values: 110.0 106.0 112.0 111.0 112.0 111.0 111.0 111.0 110.0 110.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:129.50 stddev:28.52 (22.0%) stddev-sans-first:29.45 14:21:20 INFO - PROCESS | 2525 | Values: 153.0 117.0 117.0 202.0 118.0 116.0 141.0 112.0 152.0 116.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.95 (1.1%) stddev-sans-first:1.88 14:21:20 INFO - PROCESS | 2525 | Values: 185.0 185.0 183.0 183.0 183.0 178.0 183.0 182.0 183.0 182.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#3] audio-fft Cycles:10 Average:72.30 Median:59.00 stddev:25.37 (43.0%) stddev-sans-first:26.87 14:21:20 INFO - PROCESS | 2525 | Values: 76.0 57.0 59.0 116.0 56.0 55.0 59.0 64.0 122.0 59.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:77.50 stddev:21.10 (27.2%) stddev-sans-first:22.34 14:21:20 INFO - PROCESS | 2525 | Values: 95.0 75.0 141.0 79.0 86.0 75.0 102.0 79.0 76.0 109.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:86.00 stddev:2.20 (2.6%) stddev-sans-first:2.32 14:21:20 INFO - PROCESS | 2525 | Values: 85.0 84.0 86.0 86.0 86.0 83.0 87.0 86.0 91.0 84.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#6] imaging-darkroom Cycles:10 Average:92.30 Median:92.50 stddev:0.95 (1.0%) stddev-sans-first:1.00 14:21:20 INFO - PROCESS | 2525 | Values: 92.0 93.0 93.0 94.0 92.0 93.0 91.0 92.0 91.0 92.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 14:21:20 INFO - PROCESS | 2525 | Values: 83.0 83.0 84.0 83.0 83.0 84.0 84.0 86.0 85.0 83.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:57.50 stddev:18.98 (33.0%) stddev-sans-first:19.95 14:21:20 INFO - PROCESS | 2525 | Values: 56.0 58.0 58.0 57.0 58.0 59.0 57.0 117.0 56.0 55.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.50 stddev:3.69 (5.2%) stddev-sans-first:3.10 14:21:20 INFO - PROCESS | 2525 | Values: 64.0 71.0 67.0 76.0 76.0 72.0 70.0 69.0 70.0 69.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:1.16 (1.4%) stddev-sans-first:1.22 14:21:20 INFO - PROCESS | 2525 | Values: 84.0 85.0 86.0 85.0 82.0 84.0 84.0 85.0 85.0 83.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:142.50 stddev:30.46 (21.4%) stddev-sans-first:31.98 14:21:20 INFO - PROCESS | 2525 | Values: 137.0 149.0 140.0 145.0 135.0 136.0 134.0 132.0 234.0 151.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:165.00 stddev:20.28 (12.3%) stddev-sans-first:21.12 14:21:20 INFO - PROCESS | 2525 | Values: 162.0 165.0 162.0 165.0 169.0 208.0 214.0 161.0 162.0 161.0 14:21:20 INFO - PROCESS | 2525 | 14:21:20 INFO - PROCESS | 2525 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:65.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 14:21:20 INFO - PROCESS | 2525 | Values: 65.0 66.0 66.0 67.0 66.0 64.0 65.0 65.0 65.0 65.0 14:21:20 INFO - PROCESS | 2525 | -------- Summary: end -------- 14:21:20 INFO - PROCESS | 2525 | 14:21:21 INFO - TEST-INFO | 2525: exit 0 14:21:21 INFO - TEST-OK | kraken | took 60181ms 14:21:21 INFO - SUITE-END | took 1174s 14:21:22 INFO - Completed test suite (00:19:34) 14:21:22 INFO - javascript benchmark 14:21:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3022.3350862810175, 3278.754093442991, 3435.089701229175, 3460.0830779843345, 3477.374072666922, 5687.203791469197, 5717.656940583563, 5725.0, 5810.941890581093, 5812.244408226925, 4943.728094954776, 5654.46282603152, 5784.0, 5837.970810145944, 5875.529957603392, 5194.480551944813, 5254.185601231815, 5282.260483532308, 5282.366116066078, 5371.785128594852, 5374.193870919355, 5400.864978375553, 5412.161115027177, 5434.429384914586, 5440.863978400532, 5218.739063046833, 5268.604854635918, 5283.128283833163, 5295.761690723907, 5342.946570534284, 5260.789568417259, 5263.210518422229, 5272.578193744491, 5287.0, 5326.0, 4489.7979590918485, 5227.26818245446, 5233.685978841282, 5242.161254199348, 5370.409254981949, 5516.862078448031, 5740.483356497935, 5772.2496075510335, 5798.10129429939, 5825.854353641151, 6104.786332478387, 6126.24286675468, 6172.783952561639, 6184.103305020747, 6255.906161407583, 6188.907166392508, 6222.471089957359, 6226.564140510166, 6259.561830671855, 6296.0, 5300.337457817773, 5915.024021036524, 5924.644521328734, 5933.199018132583, 5934.258217722785, 6047.15339852421, 6221.96889015552, 6245.6252624842655, 6250.781222657185, 6311.33730958252, 6119.785807496716, 6136.539759518054, 6139.539534534928, 6142.815715528542, 6151.169592105098, 6138.143176733788, 6237.313895471494, 6291.496680265568, 6307.5269354798575, 6319.115323854664, 6155.661438620829, 6217.564770466114, 6220.657863817443, 6238.968805155945, 6322.114903913456, 6298.590591611531, 6310.8422289442215, 6327.050942358591, 6327.303996560375, 6329.208848893888, 4855.0, 4936.234883593049, 4981.277714731344, 4990.72551009698, 5032.3206367139965, 3883.8446462141765, 4446.666500012512, 4472.552744725502, 4480.126375356775, 4487.349334346502, 5760.6255593386295, 5765.0, 5769.07694768835, 5815.709214539256, 5821.155932389865, 6121.84695382619, 6216.160818289563, 6280.995040793451, 6329.430351268408, 6330.01884707871, 5675.631083979528, 5777.884442311214, 5782.161586570008, 5792.652440853562, 5807.419258074159, 5440.836774896759, 5452.59105567084, 5461.699606521679, 5467.644603100785, 5476.315460567429, 5720.170575266647, 6203.31763506005, 6266.436020758154, 6361.332060133712, 6379.808605741835, 6271.717772700148, 6335.271443784024, 6337.144485494399, 6337.366263373626, 6350.4919606432295, 6343.207099112611, 6356.650384228912, 6360.0, 6364.522660800551, 6413.134226879311, 5186.740662966761, 6035.78874739382, 6110.25500820216, 6260.906086408617, 6261.624302541862, 6307.369263073656, 6327.367263273636, 6328.525360597881, 6333.493320534254, 6336.986082226822, 6141.170941922784, 6147.047207682816, 6165.260168779596, 6170.7223174958135, 6170.784022559189, 5972.910406343822, 6042.123892035543, 6078.513718902389, 6124.234470691164, 6141.201643786279, 4968.900621987467, 5991.64050156992, 6021.397860213943, 6046.606970546901, 6061.969690151521, 6108.0, 6128.264608247039, 6132.440349940305, 6141.508679305555, 6143.56995010345, 5779.508741756998, 5882.588218824641, 5921.970390148022, 5936.079907614327, 5982.252218472691], "value": 5715.8174526406165, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.3228673577408, 511.4042140905845, 522.6654940837865, 543.5217009032057, 566.2497191221631, 31705.0, 44092.0, 45417.31874021892, 45442.31836522446, 48183.759081204546, 46287.305690414585, 46861.765691171495, 46889.29666055012, 46967.76516117423, 47085.0, 8764.386492945496, 9639.421634701923, 9798.706038818847, 9801.656942007032, 9807.607695692164, 8712.303015758756, 11666.300021998708, 11887.346195959219, 11913.821292680617, 12095.455704493275, 11885.881141188607, 11904.226225295328, 12002.63992080239, 12005.459754311034, 12013.459394327276, 8706.912930870674, 11716.062714982781, 11770.941145294262, 11841.407929603529, 12010.579629713005, 11852.822207666806, 11946.701332466671, 11959.94020029903, 11973.94013029938, 11991.0, 42068.368974465404, 42659.78670106661, 42978.57021429777, 43021.92445188895, 43223.35164972528, 3056.7401770849283, 3192.712655860984, 3212.1166679163184, 3534.2578058607724, 4063.370177622444, 24489.0, 30384.54423183654, 30635.846820765975, 31158.532622010687, 31340.373192536248, 2676.0366268143453, 2685.771709404703, 2734.2754170144926, 2744.3550765570076, 2800.621916041329, 458666.0, 458882.0, 460558.6972065152, 461633.0, 462014.0, 66932.66134677376, 67671.64656706937, 67711.0, 67753.0, 67873.66063169652, 36524.81737591348, 37308.81345593309, 37593.436098458544, 38776.418353724155, 70501.64749176221, 15433.691326173413, 16908.323667053428, 17175.57061073483, 17305.913470432566, 17522.29910803579, 11978.544398398308, 12239.877601224052, 13040.021998350161, 13174.670633234055, 13309.467621295233, 19963.20147194125, 20048.89975550113, 20317.49206269855, 20513.89743051305, 20552.0, 7985.64064617082, 8064.233897779702, 8074.798130046795, 8087.797805054921, 8127.2279133482225, 901.3375169250595, 934.0705997532499, 994.0705440413092, 1019.50553981319, 1030.7835354575473, 29185.27036824054, 30485.542716859265, 30791.384172316877, 31040.22399440032, 31119.844400777853, 1053.6048981631889, 1069.0432063303456, 1081.1566977757361, 1160.8200728887036, 1166.3351889422947, 15181.316840742193, 15431.84568154304, 15706.450274240347, 15707.900446968832, 16271.632244373506, 717378.0, 723926.0, 730692.0, 732574.0, 732845.0, 63795.36204637987, 65790.67104664446, 65910.67044664746, 65960.0, 66181.33818661752, 1313.3302015971924, 1346.4748747988292, 1425.2232533269187, 1457.4898785425016, 1476.29138013754, 30108.955753273698, 31274.374512509166, 31557.36885262328, 31932.6806731939, 32327.191820204684, 54350.184747228035, 54572.72713636406, 54757.45242547523, 55347.44652553584, 55374.723126385725, 462177.0, 468751.0, 471431.0, 474923.0, 475979.0, 24037.879810600836, 45489.77255113703, 45581.316280256484, 46596.767016164704, 47440.7627961858, 47531.0, 48018.5198148014, 48194.75902620464, 49241.768955776395, 50388.99222015613, 39113.217735645696, 40577.391339130525, 41211.1757764837, 41601.37597936094, 41889.162216754885, 35121.1219719509, 37859.81070094632, 39883.61549269081, 40642.59357406388, 41346.58653413427, 37769.433458498694, 39258.803705981285, 39324.01689957774, 39393.40909886411, 40373.798131009156, 33749.0, 34616.48075278923, 34905.30189396147, 35308.82345588256, 35483.82258088693, 24474.0, 25623.871880640476, 25882.223533294033, 25885.482290354463, 26019.349516262246], "value": 20898.20623408389, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.2463543251697, 377.67708609139186, 382.4951064594735, 403.38282427885343, 409.35730902483095, 19418.320358787445, 20505.794942050576, 20523.179072837087, 20560.485987850312, 20669.689954650694, 20499.38501844947, 20610.38168854933, 20688.06903689334, 21273.0, 21613.567728645456, 5344.278522399484, 5829.9708501457435, 5888.9328507300015, 6012.428819262163, 6032.0, 5843.532517398619, 5995.370486098962, 6027.849303767419, 6043.0, 6047.0627052806885, 5814.302283725959, 5842.766289348421, 5939.07944268639, 5949.375315591866, 5981.521478281748, 5236.607254455912, 5762.279715035621, 5927.436893495111, 5947.0, 5963.403659634002, 5825.067989121762, 5851.882962340772, 5943.375945525744, 5969.731362088716, 6011.609245399035, 13258.403371848193, 13354.86645133546, 13430.798538021938, 13456.990725695612, 13490.988175886847, 2291.129370839087, 2392.7966122879575, 2434.2818868433856, 2520.911768088108, 2673.8930442782266, 9584.28117891161, 9673.177779888718, 9832.459214743258, 10134.290599658027, 10146.340487868265, 1694.9830501694948, 1731.8787684862066, 1743.171993303171, 1772.158224843202, 1801.1714611278828, 120020.0, 125065.0, 128259.09797667117, 128326.71673283241, 128757.0, 11352.978231959163, 11492.195546311845, 11568.710782230342, 11602.825957610643, 11683.240589361665, 14536.854631453762, 14634.0, 14660.0, 14678.63303417423, 14689.0, 8439.915600844035, 8507.744767656981, 8521.190486903733, 8644.22202001823, 8745.038045814956, 8355.0, 8373.95813020931, 8470.618822153017, 8474.576271186415, 8647.740567782977, 3963.9405408918888, 4498.235299999008, 4514.029483661028, 4572.817087316536, 4673.789679464432, 1660.327567335207, 1755.3154269834774, 1920.3470819921015, 1946.4841816918529, 1993.3056901633786, 876.1151237250295, 878.1789027259533, 957.2820384711466, 990.3315262197988, 1014.1481155829139, 19391.90304048499, 19780.70328945067, 20346.389608311776, 20941.476463088544, 21273.36179914605, 918.1644703319947, 954.3748844506721, 962.3648392061206, 964.4984608003939, 1019.9694009179736, 10178.745531361626, 10391.53238104287, 10836.891631083747, 10901.563937442414, 10998.560057597766, 590508.0, 601035.0, 608017.9198207961, 608379.0, 615232.0, 76122.6193869027, 76476.23523764803, 88216.0, 122427.55144897052, 137922.0, 945.3193700535741, 945.3382632157517, 972.8832540095234, 980.4264505264357, 983.4443539400359, 18381.448556543324, 18412.723809142604, 18436.907815460836, 18437.26250949947, 18452.81547184511, 52540.0, 53353.19970200528, 56207.594810130075, 56702.43297566971, 56720.0, 73073.0, 75860.24139758752, 77873.0, 79753.0, 82923.7807164453, 7797.7660670179985, 8014.959925200336, 8036.517808931483, 8233.34133269349, 8304.418690691593, 12302.569410070604, 12503.374831258583, 12716.491340346283, 12789.36053197355, 13045.543405980745, 8430.536320502431, 9667.743193384815, 10674.306170098918, 10687.732806679895, 11182.0, 10618.36289822613, 10667.146628269875, 10722.24944253926, 10942.398168100832, 11081.168912331768, 10826.621068262573, 11021.44892755343, 11220.326780393203, 11257.380844053656, 11424.314541127558, 7930.682772689028, 7934.087579928382, 8174.836503269782, 8190.099089100313, 8231.753047408587, 8062.355011598941, 9880.703578892644, 11018.779624407627, 11285.887141128484, 11286.322820630789], "value": 9321.043821514291, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.4504315838399, 1589.4278059898434, 1600.4958438092, 1861.9162137703802, 1998.0808827939156, 7864.724734634289, 8501.979762428517, 8638.352123590732, 8897.065808090147, 8927.508987005713, 8296.626651800669, 8758.08040155784, 8769.210771030623, 8776.341774366916, 8784.868226976601, 2102.1381233694187, 2194.36363454598, 2329.732080810703, 2352.1414683640432, 2388.6417037444435, 2409.759024097585, 2430.1980346485616, 2432.3554258121612, 2435.8538487690707, 2435.963460548084, 2358.2099996501192, 2400.95198096038, 2409.650600662903, 2412.107520217522, 2417.0452671194957, 2080.3342930262247, 2298.10373954158, 2359.575276450238, 2376.869272190029, 2383.642453631952, 2393.365758074112, 2412.9517409651708, 2419.5040016796515, 2420.854748715065, 2423.9515209695883, 8835.0, 8871.689490867853, 8909.821803563893, 8913.331500137516, 8921.286297096216, 5251.133562962106, 5284.233786100994, 5346.0109879672, 5404.243405923174, 5419.295491586068, 3381.4589665653625, 3463.445848664227, 3518.8592456301717, 3526.612072672004, 3587.820608969541, 1043.546057465005, 1580.170410534467, 1627.9430219942244, 1636.5990332368647, 1647.4069335039376, 11740.295582265091, 11793.705157371049, 11826.467808948619, 11863.16957812962, 11947.223430477077, 4509.842155524541, 4667.929981050287, 4716.575508204278, 4721.95278047222, 4741.976290118528, 9228.169464748205, 9269.16577508014, 9278.025807290138, 9340.365436048663, 9355.48544830041, 5046.1169295373165, 5280.551153152025, 5315.441878602768, 5318.25544423781, 5345.599080068932, 4415.889602759892, 4583.839565615188, 4663.48701642819, 4808.639352048541, 4832.081904438145, 3754.7934863582764, 3813.809309534512, 3843.6348546888003, 3877.9806100969877, 3900.1029763154065, 1165.9825102623297, 1172.7888979983513, 1172.7947609168361, 1184.0705046538599, 1187.0088476122535, 845.6575087089739, 854.7433779776746, 887.5473508510706, 897.869808877709, 941.5904081724335, 5641.830745077572, 5697.6296540724725, 5825.970870145707, 5856.619319744288, 5898.469137777534, 896.5382827843666, 925.2875286029854, 927.2442958988403, 929.7861491856776, 948.6821914658667, 3455.8963231103107, 3566.8051202847337, 3782.8273235296597, 3794.2790869734663, 3860.8841734748003, 9916.80166396668, 10966.725831854108, 11056.0, 11059.059979901638, 11089.445527723743, 10647.828738838722, 10793.0, 11007.559697612005, 11017.173711971634, 11040.061594764375, 8161.510309381456, 8333.999920009517, 8434.072252052269, 8436.746897593082, 8447.197516235949, 5600.523955463668, 5675.858103547444, 5721.056025755704, 5773.740181691918, 5774.82675519735, 8689.8696519551, 9932.553035113582, 10290.12533934624, 10298.124659403735, 10304.021117993778, 8570.871436928326, 9939.304248702521, 10060.0, 10502.737431564272, 10543.209259305737, 5758.625689330348, 6355.491560675227, 6581.308962558863, 6683.598984060777, 6888.552244104118, 8341.916580834113, 8455.915440845758, 8475.110113438, 8529.232369086563, 8552.57237138153, 7310.59791711461, 7416.6291685414435, 7524.209957954555, 7659.502132361378, 7680.923190768244, 7256.947742577402, 7372.373348265458, 7380.446466514925, 7483.289087536676, 7526.548407095592, 6161.815145545641, 7089.220185779664, 7099.538529995534, 7131.536450130725, 7135.357817796424, 4785.95214047869, 4810.13417584838, 4914.140025495595, 4950.331705219894, 5101.20931255656, 4933.531314525115, 5004.674696144739, 5016.08293416447, 5059.721715305546, 5242.9475705243985], "value": 4590.187140306235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.8597645083262, 308.2355757720852, 318.12198332602026, 324.77590462580804, 327.53980657176675, 5002.574781143602, 5033.094043072246, 5401.78392864285, 5409.9459005409935, 5420.159875219338, 4281.678874084449, 4527.071950250199, 5100.719460429675, 5120.874836942664, 5235.162374020158, 3627.877691550433, 4078.084169818579, 4090.668319058138, 4114.259433302004, 4142.860713303843, 3543.4330507118866, 3826.0, 3828.7747920665397, 4093.4678491796026, 4125.566815484376, 3449.8275086245712, 3882.844686212548, 4093.8362465501345, 4127.298359278915, 4144.751314921099, 3766.8493260269693, 4029.516458025041, 4056.249593825152, 4069.1251380953236, 4072.490938632671, 3553.5125412211296, 3560.928781424357, 3566.696830769388, 3568.375534281516, 3570.2145527983803, 4190.979045104786, 4633.86098417048, 4928.937120717948, 4936.185529387682, 5114.923276150861, 4614.954130274003, 4666.300054991744, 4673.462551806551, 4683.882902927454, 4715.836159405446, 4601.470830854428, 5583.58123140762, 5588.748506317185, 5650.6892120933335, 5730.226419433365, 3259.429599820022, 3807.058400215882, 3938.4683067786054, 3946.9013274668077, 3949.269385163754, 5890.617109887887, 5913.438223368816, 5936.278479241015, 5941.762329506814, 5965.3736357682255, 3924.823382947774, 3999.8800035998966, 4015.5382131055303, 4025.4163146343617, 4049.7445791804635, 3929.1159489115294, 3949.1311911379453, 4100.282450571198, 4104.774237416971, 4122.154958233555, 3971.4638523799495, 4249.76756740548, 4638.675292729544, 4673.392458980311, 4677.859664210011, 5085.923711144335, 5624.440879399864, 5636.830895073154, 5660.235868157828, 5706.9727449059565, 1067.6743593204146, 1203.4825025239231, 1228.8648248692637, 1338.9464421423033, 1380.1029330934973, 881.722257488889, 888.7866911941217, 911.3757076402685, 937.9155875971195, 951.9666811661558, 4623.352730617717, 5172.637915345965, 5192.714400707921, 5241.895162096736, 5285.788568457218, 4663.626909847204, 5089.3638295213095, 5282.207668849626, 5315.415304316522, 5718.313802343745, 4965.553100220997, 5293.935365207572, 5302.8333766571295, 5323.121684922022, 5369.858632699914, 2615.3853844346563, 2653.8057873956795, 2980.463516566995, 2985.7312841844337, 3044.9647119979313, 4781.880452988703, 5467.671939683552, 5521.785207254397, 5822.592418530662, 6164.722587483574, 5391.568674506108, 6031.07005758162, 6079.361667024988, 6095.603785753912, 6097.298810636745, 5551.3893471718075, 5901.554119240727, 6161.907571386433, 6168.259808822791, 6184.381561843779, 4903.0, 5260.421353651172, 5631.380548139619, 5858.794942177174, 5919.0, 5582.134769110794, 5797.536197104306, 5838.824835254949, 5852.561057920758, 5855.130985817004, 5928.733207005609, 6055.969720151371, 6143.095640351506, 6230.504678876946, 6246.377101281337, 3519.560054993126, 3777.395616701315, 3891.416287556889, 3905.336092864163, 3960.5643379228836, 5049.444561098349, 5841.415858414124, 5937.732802024006, 5982.46157845796, 6001.939980600313, 5227.784912278997, 5287.074761916572, 5308.230306605444, 5587.497125258747, 5786.132080188006, 4827.275908613736, 5216.269722238813, 5308.362996440452, 5364.2490051393615, 5651.463111004448, 4493.6854420190275, 5445.529706979014, 5492.395836457907, 5613.887722245614, 5623.2408624835125, 4635.0, 5041.899162016666, 5663.932664577482, 5698.572607054571, 5703.515201207943, 4699.177643912233, 4963.602911766978, 5361.490658387447, 5565.6104072714525, 5567.331920169606], "value": 4110.336913493776, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.7724603273772, 676.452073820206, 736.6243215959859, 746.8954346391506, 752.3379426105026, 13824.170549767021, 15011.099334039951, 15303.15832629205, 15333.233338333066, 15342.769858452104, 12125.211861229036, 13742.175469471838, 13794.44822207113, 13822.930885345559, 13846.861531384684, 752.3229093815569, 913.7441516375392, 947.052947052947, 951.5860600638745, 956.3974695941547, 887.2946007923716, 904.0597778310549, 934.22926085979, 940.3323640215456, 952.2477242978039, 771.5409331447808, 867.1718508824058, 1006.7986402719449, 1007.5566750629736, 1012.1143999000874, 943.4386540008718, 982.6315131825565, 985.3348989432104, 989.6635144050986, 989.7723523589578, 689.8965155226706, 701.6000879498688, 743.0117943135616, 743.1750756660103, 744.9292317229855, 14536.200508972002, 14608.853911460856, 14642.267886605627, 14673.853261467355, 14678.486252981202, 12176.147669663127, 12272.32502212378, 12278.447469863877, 12308.26150430977, 12414.937925310316, 9111.17999380059, 10691.572337106507, 10790.406527640976, 10854.077403420719, 11173.776524469466, 4008.939865902014, 4031.717779755418, 4039.1719697462245, 4098.631123198897, 4191.91616167675, 70267.2973270266, 70436.0, 71543.28456715419, 71618.64190679065, 72415.63792181111, 8303.501789892625, 9296.767580810398, 9314.580843862042, 9398.765030874147, 9569.808603827885, 20494.18023279082, 21236.78763212379, 21245.150193992067, 21248.0, 21386.0, 7292.45306602007, 7317.231690672509, 7414.851702966019, 7473.40212782987, 7482.775516734507, 3876.5348158221195, 3942.56631770505, 3999.060220848098, 4046.4537287465814, 4053.5135783706146, 3279.8524066417067, 3305.4380755271663, 3308.156420112856, 3368.814715190639, 3381.9492707609406, 486.5839707050492, 539.9595030372659, 599.0534954771538, 618.1994317359031, 631.5957787016313, 485.9562639362474, 584.7047241143196, 591.7396345608005, 614.443928244939, 614.5022531749297, 8005.759827205193, 8191.139930307351, 8407.159284071666, 8516.574171291411, 8517.190866867637, 469.77920377422555, 583.439897698206, 610.2463457629824, 610.5072553394592, 630.7762939896586, 3822.5412950446125, 3970.7419017763755, 3996.3805610130476, 4011.939820902689, 4065.7560546367313, 99868.0, 100879.0, 101441.71165766875, 101496.0, 101965.0, 56998.43001570014, 74151.0, 81087.0, 88596.6710499343, 89177.55411222903, 77397.0, 77954.0, 78944.72186679934, 79449.0, 79937.60031199922, 7780.766577002699, 7860.449768516149, 7872.685092596232, 7925.564093974886, 8038.718644847636, 47766.761166193945, 47972.520274798204, 48317.75841120772, 49613.25580116373, 49966.50033499764, 63436.414089646285, 64556.6772166155, 64777.0, 65206.0, 66816.0, 10242.948785256027, 10250.897491024994, 10675.039246467855, 11263.155263355446, 11317.71705707364, 12208.511659533518, 12252.632421027383, 12414.751704966031, 12429.192102513307, 12592.433340499883, 11174.49714762854, 11687.590934317259, 11708.414579270831, 11894.840972451337, 12058.698532536757, 10578.047975682226, 10817.837732434176, 11589.362585057903, 11620.825687614524, 11890.881091188978, 11324.94337528307, 11440.02759765396, 11442.542298307975, 11842.585509507126, 11859.584914528295, 9731.708048758548, 9758.463284519135, 9856.753581160528, 10014.799704005733, 10100.646477373257, 8684.914385701788, 8745.081766414434, 9057.68298109563, 10048.54781534818, 10104.343217690826], "value": 6895.302239896235, "unit": "score"}], "name": "dromaeo_css", "value": 7247.029200796497, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 106.0, 112.0, 111.0, 112.0, 111.0, 111.0, 111.0, 110.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 117.0, 117.0, 202.0, 118.0, 116.0, 141.0, 112.0, 152.0, 116.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 183.0, 183.0, 178.0, 183.0, 182.0, 183.0, 182.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 57.0, 59.0, 116.0, 56.0, 55.0, 59.0, 64.0, 122.0, 59.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 75.0, 141.0, 79.0, 86.0, 75.0, 102.0, 79.0, 76.0, 109.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 86.0, 86.0, 86.0, 83.0, 87.0, 86.0, 91.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 92.0, 93.0, 91.0, 92.0, 91.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 83.0, 83.0, 84.0, 84.0, 86.0, 85.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 57.0, 58.0, 59.0, 57.0, 117.0, 56.0, 55.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 67.0, 76.0, 76.0, 72.0, 70.0, 69.0, 70.0, 69.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 85.0, 82.0, 84.0, 84.0, 85.0, 85.0, 83.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 149.0, 140.0, 145.0, 135.0, 136.0, 134.0, 132.0, 234.0, 151.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 162.0, 165.0, 169.0, 208.0, 214.0, 161.0, 162.0, 161.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 66.0, 64.0, 65.0, 65.0, 65.0, 65.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1458.8, "alertThreshold": 2.0}]} 14:21:22 INFO - Return code: 0 14:21:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:21:22 ERROR - # TBPL SUCCESS # 14:21:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:21:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:21:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:21:22 INFO - 2016-05-23 14:21:22.140 screenresolution[2529:40137] starting screenresolution argv=screenresolution get 14:21:22 INFO - 2016-05-23 14:21:22.142 screenresolution[2529:40137] Display 0: 1600x1200x32@60 14:21:22 INFO - 2016-05-23 14:21:22.148 screenresolution[2530:40140] starting screenresolution argv=screenresolution list 14:21:22 INFO - Available Modes on Display 0 14:21:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:21:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:21:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:21:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:21:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:21:22 INFO - Intel Iris: 14:21:22 INFO - Chipset Model: Intel Iris 14:21:22 INFO - Type: GPU 14:21:22 INFO - Bus: Built-In 14:21:22 INFO - VRAM (Dynamic, Max): 1536 MB 14:21:22 INFO - Vendor: Intel (0x8086) 14:21:22 INFO - Device ID: 0x0a2e 14:21:22 INFO - Revision ID: 0x0009 14:21:22 INFO - Displays: 14:21:22 INFO - Display: 14:21:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:21:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:21:22 INFO - Display Serial Number: 1600x1200 60 14:21:22 INFO - Main Display: Yes 14:21:22 INFO - Mirror: Off 14:21:22 INFO - Online: Yes 14:21:22 INFO - Rotation: Supported 14:21:22 INFO - Return code: 0 14:21:22 INFO - Running post-action listener: _resource_record_post_action 14:21:22 INFO - ##### 14:21:22 INFO - ##### Finished run-tests step (success) 14:21:22 INFO - ##### 14:21:22 INFO - Running post-run listener: _resource_record_post_run 14:21:22 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 385024; Write bytes: 693877760; Read time: 2; Write time: 1929 14:21:22 INFO - TinderboxPrint: CPU usage
54.2% 14:21:22 INFO - TinderboxPrint: I/O read bytes / time
385,024 / 2 14:21:22 INFO - TinderboxPrint: I/O write bytes / time
693,877,760 / 1,929 14:21:22 INFO - TinderboxPrint: CPU idle
2,217.4 (45.8%) 14:21:22 INFO - TinderboxPrint: CPU system
977.4 (20.2%) 14:21:22 INFO - TinderboxPrint: CPU user
1,647.0 (34.0%) 14:21:22 INFO - TinderboxPrint: Swap in / out
396,083,200 / 0 14:21:22 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396960256; Write bytes: 397997568; Read time: 30504; Write time: 855 14:21:22 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 385024; Write bytes: 294864384; Read time: 2; Write time: 1068 14:21:22 INFO - Running post-run listener: _upload_blobber_files 14:21:22 INFO - Blob upload gear active. 14:21:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:21:22 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 14:21:22 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'] 14:21:22 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 14:21:23 INFO - (blobuploader) - INFO - Open directory for files ... 14:21:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:21:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:21:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:23 INFO - (blobuploader) - INFO - Done attempting. 14:21:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:21:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:21:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:24 INFO - (blobuploader) - INFO - Done attempting. 14:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:21:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:21:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:21:28 INFO - (blobuploader) - INFO - Done attempting. 14:21:28 INFO - (blobuploader) - INFO - Iteration through files over. 14:21:28 INFO - Return code: 0 14:21:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:21:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:21:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d41c0780ab8c02be89ddbe51c8fcd72d38e933809358ee4264919c0c7c81233aed03cf041b0bbde8cece34ea6d75103d78e1176e3b390d3d2dae6e287e6eb54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7cd7fb7f577e2835ad06fc4e18f8ee4ffa889d1637e4b8d4808da6a082807c31347b81918db3125b0a36ef853d4cbee533bea89284bdd2c38257cb699937e1a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95a5c25725a829f842dc12c837ea03b6298f27568576f5014aba77648528db508cbeb49ac050029575a14be4605e033e9072002fcbe05a443833d9ccd9708ffe"} 14:21:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:21:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:21:28 INFO - Contents: 14:21:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d41c0780ab8c02be89ddbe51c8fcd72d38e933809358ee4264919c0c7c81233aed03cf041b0bbde8cece34ea6d75103d78e1176e3b390d3d2dae6e287e6eb54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7cd7fb7f577e2835ad06fc4e18f8ee4ffa889d1637e4b8d4808da6a082807c31347b81918db3125b0a36ef853d4cbee533bea89284bdd2c38257cb699937e1a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95a5c25725a829f842dc12c837ea03b6298f27568576f5014aba77648528db508cbeb49ac050029575a14be4605e033e9072002fcbe05a443833d9ccd9708ffe"} 14:21:28 INFO - Running post-run listener: copy_logs_to_upload_dir 14:21:28 INFO - Copying logs to upload dir... 14:21:28 INFO - mkdir: /builds/slave/test/build/upload/logs 14:21:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.763446 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-23 14:21:28.094808) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 14:21:28.098849) ========= 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.baDQHObdI7/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.mdhokoK7kC/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d41c0780ab8c02be89ddbe51c8fcd72d38e933809358ee4264919c0c7c81233aed03cf041b0bbde8cece34ea6d75103d78e1176e3b390d3d2dae6e287e6eb54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7cd7fb7f577e2835ad06fc4e18f8ee4ffa889d1637e4b8d4808da6a082807c31347b81918db3125b0a36ef853d4cbee533bea89284bdd2c38257cb699937e1a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95a5c25725a829f842dc12c837ea03b6298f27568576f5014aba77648528db508cbeb49ac050029575a14be4605e033e9072002fcbe05a443833d9ccd9708ffe"} build_url:https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011147 build_url: 'https://queue.taskcluster.net/v1/task/IYr2NLyURuajldZr-yH0_w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5d41c0780ab8c02be89ddbe51c8fcd72d38e933809358ee4264919c0c7c81233aed03cf041b0bbde8cece34ea6d75103d78e1176e3b390d3d2dae6e287e6eb54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7cd7fb7f577e2835ad06fc4e18f8ee4ffa889d1637e4b8d4808da6a082807c31347b81918db3125b0a36ef853d4cbee533bea89284bdd2c38257cb699937e1a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95a5c25725a829f842dc12c837ea03b6298f27568576f5014aba77648528db508cbeb49ac050029575a14be4605e033e9072002fcbe05a443833d9ccd9708ffe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 14:21:28.127862) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 14:21:28.128196) ========= 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.baDQHObdI7/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.mdhokoK7kC/Listeners TMPDIR=/var/folders/gx/03x7wz8n29n54z05qtqj40wr00000w/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.005190 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 14:21:28.177420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 14:21:28.177736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 14:21:28.178216) ========= ========= Total master_lag: 0.17 =========