builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0193 starttime: 1464096370.61 results: success (0) buildid: 20160524051813 builduid: 625de1765a064d5fa867a57242d0826c revision: 74fe05a5f015f517f835f0b560b074a941103481 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:10.609248) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:10.609847) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:10.610170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:10.646997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:10.647291) ========= 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.Kco8RkJduc/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.xGAHrDJmAz/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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-24 06:26:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2016-05-24 06:26:11 (15.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.481421 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:11.153496) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:11.153818) ========= 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.Kco8RkJduc/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.xGAHrDJmAz/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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.027916 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:11.201928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 06:26:11.202332) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 74fe05a5f015f517f835f0b560b074a941103481 --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 74fe05a5f015f517f835f0b560b074a941103481 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Kco8RkJduc/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.xGAHrDJmAz/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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-24 06:26:11,279 truncating revision to first 12 chars 2016-05-24 06:26:11,279 Setting DEBUG logging. 2016-05-24 06:26:11,279 attempt 1/10 2016-05-24 06:26:11,279 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/74fe05a5f015?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 06:26:12,612 unpacking tar archive at: fx-team-74fe05a5f015/testing/mozharness/ program finished with exit code 0 elapsedTime=1.577304 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 06:26:12.797577) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:12.797877) ========= script_repo_revision: 74fe05a5f015f517f835f0b560b074a941103481 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:12.798265) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:12.798528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 06:26:12.812850) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-24 06:26:12.813125) ========= /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.Kco8RkJduc/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.xGAHrDJmAz/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:26:13 INFO - MultiFileLogger online at 20160524 06:26:13 in /builds/slave/test 06:26:13 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 06:26:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:26:13 INFO - {'append_to_log': False, 06:26:13 INFO - 'base_work_dir': '/builds/slave/test', 06:26:13 INFO - 'blob_upload_branch': 'Fx-Team', 06:26:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:26:13 INFO - 'branch': 'Fx-Team', 06:26:13 INFO - 'buildbot_json_path': 'buildprops.json', 06:26:13 INFO - 'config_files': ('talos/mac_config.py',), 06:26:13 INFO - 'default_actions': ('clobber', 06:26:13 INFO - 'read-buildbot-config', 06:26:13 INFO - 'download-and-extract', 06:26:13 INFO - 'populate-webroot', 06:26:13 INFO - 'create-virtualenv', 06:26:13 INFO - 'install', 06:26:13 INFO - 'run-tests'), 06:26:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:26:13 INFO - 'download_minidump_stackwalk': True, 06:26:13 INFO - 'download_symbols': 'ondemand', 06:26:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:26:13 INFO - 'tooltool.py': '/tools/tooltool.py', 06:26:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:26:13 INFO - '/tools/misc-python/virtualenv.py')}, 06:26:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:26:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:26:13 INFO - 'installer_path': 'installer.exe', 06:26:13 INFO - 'log_level': 'info', 06:26:13 INFO - 'log_name': 'talos', 06:26:13 INFO - 'log_to_console': True, 06:26:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:26:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:26:13 INFO - 'opt_config_files': (), 06:26:13 INFO - 'pip_index': False, 06:26:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:26:13 INFO - 'cmd': ('bash', 06:26:13 INFO - '-c', 06:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:26:13 INFO - 'enabled': True, 06:26:13 INFO - 'halt_on_failure': False, 06:26:13 INFO - 'name': 'check_screen_resolution'},), 06:26:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:26:13 INFO - 'cmd': ('bash', 06:26:13 INFO - '-c', 06:26:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:26:13 INFO - 'enabled': True, 06:26:13 INFO - 'halt_on_failure': False, 06:26:13 INFO - 'name': 'check_screen_resolution'},), 06:26:13 INFO - 'run_cmd_checks_enabled': True, 06:26:13 INFO - 'sps_profile': False, 06:26:13 INFO - 'sps_profile_interval': 0, 06:26:13 INFO - 'suite': 'dromaeojs', 06:26:13 INFO - 'system_bits': '32', 06:26:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:26:13 INFO - 'title': 't-yosemite-r7-0193', 06:26:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:26:13 INFO - 'use_talos_json': True, 06:26:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:26:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:26:13 INFO - 'work_dir': 'build'} 06:26:13 INFO - ##### 06:26:13 INFO - ##### Running clobber step. 06:26:13 INFO - ##### 06:26:13 INFO - Running pre-action listener: _resource_record_pre_action 06:26:13 INFO - Running main action method: clobber 06:26:13 INFO - rmtree: /builds/slave/test/build 06:26:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:26:13 INFO - Running post-action listener: _resource_record_post_action 06:26:13 INFO - ##### 06:26:13 INFO - ##### Finished clobber step (success) 06:26:13 INFO - ##### 06:26:13 INFO - ##### 06:26:13 INFO - ##### Running read-buildbot-config step. 06:26:13 INFO - ##### 06:26:13 INFO - Running pre-action listener: _resource_record_pre_action 06:26:13 INFO - Running main action method: read_buildbot_config 06:26:13 INFO - Using buildbot properties: 06:26:13 INFO - { 06:26:13 INFO - "project": "", 06:26:13 INFO - "product": "firefox", 06:26:13 INFO - "script_repo_revision": "production", 06:26:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:26:13 INFO - "repository": "", 06:26:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:26:13 INFO - "buildid": "20160524051813", 06:26:13 INFO - "pgo_build": "False", 06:26:13 INFO - "basedir": "/builds/slave/test", 06:26:13 INFO - "buildnumber": 36, 06:26:13 INFO - "slavename": "t-yosemite-r7-0193", 06:26:13 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:26:13 INFO - "platform": "yosemite_r7", 06:26:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:26:13 INFO - "branch": "fx-team", 06:26:13 INFO - "repo_path": "integration/fx-team", 06:26:13 INFO - "revision": "74fe05a5f015f517f835f0b560b074a941103481", 06:26:13 INFO - "stage_platform": "macosx64", 06:26:13 INFO - "builduid": "625de1765a064d5fa867a57242d0826c", 06:26:13 INFO - "slavebuilddir": "test" 06:26:13 INFO - } 06:26:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:26:13 INFO - Running post-action listener: _resource_record_post_action 06:26:13 INFO - ##### 06:26:13 INFO - ##### Finished read-buildbot-config step (success) 06:26:13 INFO - ##### 06:26:13 INFO - ##### 06:26:13 INFO - ##### Running download-and-extract step. 06:26:13 INFO - ##### 06:26:13 INFO - Running pre-action listener: _resource_record_pre_action 06:26:13 INFO - Running main action method: download_and_extract 06:26:13 INFO - mkdir: /builds/slave/test/build/tests 06:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:13 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:26:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:26:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:26:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:19 INFO - Downloaded 1199 bytes. 06:26:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:26:19 INFO - Using the following test package requirements: 06:26:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:26:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:26:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:26:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'jsshell-mac.zip'], 06:26:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:26:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:26:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:26:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:26:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:26:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:26:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:26:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:26:19 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')]} 06:26:19 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:26:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:26:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:26:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:21 INFO - Downloaded 18630437 bytes. 06:26:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 06:26:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 06:26:24 INFO - Return code: 0 06:26:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:26:24 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:26:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:26:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:26:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:28 INFO - Downloaded 18630437 bytes. 06:26: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'] 06:26: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 06:26:32 INFO - Return code: 0 06:26: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')]} 06:26:32 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:26:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:26:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:26:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:33 INFO - Downloaded 10840359 bytes. 06:26: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'] 06:26: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 06:26:34 INFO - Return code: 0 06:26:34 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')]} 06:26:34 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:26:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:26:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:26:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/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 06:26:37 INFO - Downloaded 110141401 bytes. 06:26:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:26:37 INFO - mkdir: /builds/slave/test/properties 06:26:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:26:37 INFO - Writing to file /builds/slave/test/properties/build_url 06:26:37 INFO - Contents: 06:26:37 INFO - build_url:https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:26:38 INFO - Running post-action listener: _resource_record_post_action 06:26:38 INFO - Running post-action listener: set_extra_try_arguments 06:26:38 INFO - ##### 06:26:38 INFO - ##### Finished download-and-extract step (success) 06:26:38 INFO - ##### 06:26:38 INFO - ##### 06:26:38 INFO - ##### Running populate-webroot step. 06:26:38 INFO - ##### 06:26:38 INFO - Running pre-action listener: _resource_record_pre_action 06:26:38 INFO - Running main action method: populate_webroot 06:26:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:26:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:26:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:26:38 INFO - '--tppagecycles', 06:26:38 INFO - '7'], 06:26:38 INFO - 'tests': ['tsvgm']}}, 06:26:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:26:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:26:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:26:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:26:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:26:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:26:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['damp', 'tps']}, 06:26:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'tests': ['damp', 'tps']}, 06:26:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['dromaeo_dom']}, 06:26:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:26:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['a11yr', 06:26:38 INFO - 'ts_paint', 06:26:38 INFO - 'tpaint', 06:26:38 INFO - 'sessionrestore', 06:26:38 INFO - 'sessionrestore_no_auto_restore', 06:26:38 INFO - 'tabpaint']}, 06:26:38 INFO - 'other-e10s': {'tests': ['a11yr', 06:26:38 INFO - 'ts_paint', 06:26:38 INFO - 'tpaint', 06:26:38 INFO - 'sessionrestore', 06:26:38 INFO - 'sessionrestore_no_auto_restore', 06:26:38 INFO - 'tabpaint']}, 06:26:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:26:38 INFO - 'ts_paint', 06:26:38 INFO - 'tpaint', 06:26:38 INFO - 'sessionrestore', 06:26:38 INFO - 'sessionrestore_no_auto_restore', 06:26:38 INFO - 'tabpaint']}, 06:26:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:26:38 INFO - 'ts_paint', 06:26:38 INFO - 'tpaint', 06:26:38 INFO - 'sessionrestore', 06:26:38 INFO - 'sessionrestore_no_auto_restore', 06:26:38 INFO - 'tabpaint']}, 06:26:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['a11yr', 06:26:38 INFO - 'ts_paint', 06:26:38 INFO - 'tpaint', 06:26:38 INFO - 'sessionrestore', 06:26:38 INFO - 'sessionrestore_no_auto_restore', 06:26:38 INFO - 'tabpaint']}, 06:26:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['a11yr', 06:26:38 INFO - 'ts_paint', 06:26:38 INFO - 'tpaint', 06:26:38 INFO - 'sessionrestore', 06:26:38 INFO - 'sessionrestore_no_auto_restore', 06:26:38 INFO - 'tabpaint']}, 06:26:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['tsvgx', 06:26:38 INFO - 'tsvgr_opacity', 06:26:38 INFO - 'tart', 06:26:38 INFO - 'tscrollx', 06:26:38 INFO - 'cart']}, 06:26:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:26:38 INFO - 'tsvgr_opacity', 06:26:38 INFO - 'tart', 06:26:38 INFO - 'tscrollx', 06:26:38 INFO - 'cart']}, 06:26:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'talos_options': ['--disable-e10s'], 06:26:38 INFO - 'tests': ['tp5o']}, 06:26:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'tests': ['tp5o']}, 06:26:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'talos_options': ['--disable-e10s', 06:26:38 INFO - '--xperf_path', 06:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:26:38 INFO - 'tests': ['tp5n']}, 06:26:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:26:38 INFO - 'talos_options': ['--xperf_path', 06:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:26:38 INFO - 'tests': ['tp5n']}}, 06:26:38 INFO - 'talos.zip': {'path': '', 06:26:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:26:38 INFO - Running post-action listener: _resource_record_post_action 06:26:38 INFO - ##### 06:26:38 INFO - ##### Finished populate-webroot step (success) 06:26:38 INFO - ##### 06:26:38 INFO - ##### 06:26:38 INFO - ##### Running create-virtualenv step. 06:26:38 INFO - ##### 06:26:38 INFO - Running pre-action listener: _resource_record_pre_action 06:26:38 INFO - Running main action method: create_virtualenv 06:26:38 INFO - Creating virtualenv /builds/slave/test/build/venv 06:26:38 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 06:26:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:26:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:26:38 INFO - Using real prefix '/tools/python27' 06:26:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:26:39 INFO - Installing distribute.............................................................................................................................................................................................done. 06:26:42 INFO - Installing pip.................done. 06:26:42 INFO - Return code: 0 06:26:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:26:42 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')]} 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:42 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')]} 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:42 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 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:26:42 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 06:26:42 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 06:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:42 INFO - 'HOME': '/Users/cltbld', 06:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:42 INFO - 'LOGNAME': 'cltbld', 06:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:42 INFO - 'MOZ_NO_REMOTE': '1', 06:26:42 INFO - 'NO_EM_RESTART': '1', 06:26:42 INFO - 'PAGER': '/bin/cat', 06:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:42 INFO - 'PWD': '/builds/slave/test', 06:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:42 INFO - 'SHELL': '/bin/bash', 06:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:26:42 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:42 INFO - 'USER': 'cltbld', 06:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:42 INFO - 'XPC_FLAGS': '0x0', 06:26:42 INFO - 'XPC_SERVICE_NAME': '0', 06:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:42 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 06:26:42 INFO - Cleaning up... 06:26:42 INFO - Return code: 0 06:26:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:26:42 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')]} 06:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:42 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')]} 06:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:42 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 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:26:42 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 06:26:42 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 06:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:42 INFO - 'HOME': '/Users/cltbld', 06:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:42 INFO - 'LOGNAME': 'cltbld', 06:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:42 INFO - 'MOZ_NO_REMOTE': '1', 06:26:42 INFO - 'NO_EM_RESTART': '1', 06:26:42 INFO - 'PAGER': '/bin/cat', 06:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:42 INFO - 'PWD': '/builds/slave/test', 06:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:42 INFO - 'SHELL': '/bin/bash', 06:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:26:42 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:42 INFO - 'USER': 'cltbld', 06:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:42 INFO - 'XPC_FLAGS': '0x0', 06:26:42 INFO - 'XPC_SERVICE_NAME': '0', 06:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:42 INFO - Downloading/unpacking psutil>=3.1.1 06:26:42 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 06:26:42 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 06:26:42 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 06:26:42 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 06:26:42 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 06:26:42 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 06:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:26:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:45 INFO - Installing collected packages: psutil 06:26:45 INFO - Running setup.py install for psutil 06:26:45 INFO - building 'psutil._psutil_osx' extension 06:26: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:26:46 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 06:26:46 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 06:26:46 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 06:26:46 INFO - building 'psutil._psutil_posix' extension 06:26:46 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 06:26:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:26:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:26:46 INFO - ^ 06:26:46 INFO - 1 warning generated. 06:26:46 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 06:26:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:47 INFO - Successfully installed psutil 06:26:47 INFO - Cleaning up... 06:26:47 INFO - Return code: 0 06:26:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:26:47 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')]} 06:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:47 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')]} 06:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:47 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 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:26:47 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 06:26:47 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 06:26:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:26:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:47 INFO - 'HOME': '/Users/cltbld', 06:26:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:47 INFO - 'LOGNAME': 'cltbld', 06:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:47 INFO - 'MOZ_NO_REMOTE': '1', 06:26:47 INFO - 'NO_EM_RESTART': '1', 06:26:47 INFO - 'PAGER': '/bin/cat', 06:26:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:47 INFO - 'PWD': '/builds/slave/test', 06:26:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:47 INFO - 'SHELL': '/bin/bash', 06:26:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:26:47 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:26:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:47 INFO - 'USER': 'cltbld', 06:26:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:47 INFO - 'XPC_FLAGS': '0x0', 06:26:47 INFO - 'XPC_SERVICE_NAME': '0', 06:26:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:26:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:26:49 INFO - Installing collected packages: mozsystemmonitor 06:26:49 INFO - Running setup.py install for mozsystemmonitor 06:26:49 INFO - Successfully installed mozsystemmonitor 06:26:49 INFO - Cleaning up... 06:26:50 INFO - Return code: 0 06:26:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:50 INFO - 'HOME': '/Users/cltbld', 06:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:50 INFO - 'LOGNAME': 'cltbld', 06:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:50 INFO - 'MOZ_NO_REMOTE': '1', 06:26:50 INFO - 'NO_EM_RESTART': '1', 06:26:50 INFO - 'PAGER': '/bin/cat', 06:26:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:50 INFO - 'PWD': '/builds/slave/test', 06:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:50 INFO - 'SHELL': '/bin/bash', 06:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:26:50 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:50 INFO - 'USER': 'cltbld', 06:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:50 INFO - 'XPC_FLAGS': '0x0', 06:26:50 INFO - 'XPC_SERVICE_NAME': '0', 06:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:50 INFO - Downloading/unpacking blobuploader==1.2.4 06:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:52 INFO - Downloading blobuploader-1.2.4.tar.gz 06:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:26:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:26:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:26:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:53 INFO - Downloading docopt-0.6.1.tar.gz 06:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:26:54 INFO - Installing collected packages: blobuploader, requests, docopt 06:26:54 INFO - Running setup.py install for blobuploader 06:26:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:26:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:26:54 INFO - Running setup.py install for requests 06:26:54 INFO - Running setup.py install for docopt 06:26:54 INFO - Successfully installed blobuploader requests docopt 06:26:54 INFO - Cleaning up... 06:26:54 INFO - Return code: 0 06:26:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:26:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:26:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:54 INFO - 'HOME': '/Users/cltbld', 06:26:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:54 INFO - 'LOGNAME': 'cltbld', 06:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:54 INFO - 'MOZ_NO_REMOTE': '1', 06:26:54 INFO - 'NO_EM_RESTART': '1', 06:26:54 INFO - 'PAGER': '/bin/cat', 06:26:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:54 INFO - 'PWD': '/builds/slave/test', 06:26:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:54 INFO - 'SHELL': '/bin/bash', 06:26:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:26:54 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:26:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:54 INFO - 'USER': 'cltbld', 06:26:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:54 INFO - 'XPC_FLAGS': '0x0', 06:26:54 INFO - 'XPC_SERVICE_NAME': '0', 06:26:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-nHNVpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-yfGkkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-5sI9a_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-e97_Mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-QFxML4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-K5hVYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-35nZWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-tWmTx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:26:55 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-LkFiqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-dqqwid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-4Q8Ral-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-OLOwBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-kzHMMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-WffcOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-O48ktE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-CI1HU2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:26:56 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-eQUQSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:26:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:26:56 INFO - Running setup.py install for manifestparser 06:26:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:26:57 INFO - Running setup.py install for mozcrash 06:26:57 INFO - Running setup.py install for mozdebug 06:26:57 INFO - Running setup.py install for mozdevice 06:26:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:26:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:26:57 INFO - Running setup.py install for mozfile 06:26:57 INFO - Running setup.py install for mozhttpd 06:26:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:26:57 INFO - Running setup.py install for mozinfo 06:26:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:26:57 INFO - Running setup.py install for mozInstall 06:26:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Running setup.py install for mozleak 06:26:58 INFO - Running setup.py install for mozlog 06:26:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Running setup.py install for moznetwork 06:26:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Running setup.py install for mozprocess 06:26:58 INFO - Running setup.py install for mozprofile 06:26:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:26:58 INFO - Running setup.py install for mozrunner 06:26:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:26:59 INFO - Running setup.py install for mozscreenshot 06:26:59 INFO - Running setup.py install for moztest 06:26:59 INFO - Running setup.py install for mozversion 06:26:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:26:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:26:59 INFO - Cleaning up... 06:26:59 INFO - Return code: 0 06:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:26:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:26:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:26:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:59 INFO - 'HOME': '/Users/cltbld', 06:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:59 INFO - 'LOGNAME': 'cltbld', 06:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:59 INFO - 'MOZ_NO_REMOTE': '1', 06:26:59 INFO - 'NO_EM_RESTART': '1', 06:26:59 INFO - 'PAGER': '/bin/cat', 06:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:59 INFO - 'PWD': '/builds/slave/test', 06:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:59 INFO - 'SHELL': '/bin/bash', 06:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:26:59 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:59 INFO - 'USER': 'cltbld', 06:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:59 INFO - 'XPC_FLAGS': '0x0', 06:26:59 INFO - 'XPC_SERVICE_NAME': '0', 06:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:59 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-M9AijO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:59 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-n7mjGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:59 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)) 06:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:59 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-3y32TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-j46IFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-8tEIqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-AxyBnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-pnsuqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Cy57Ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-_tJpkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-o7SB2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:27:00 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)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Jf24Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:27:00 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-mfv8TP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:27:01 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)) 06:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:27:01 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-_D3rHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:27:01 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-ulraej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:27:01 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)) 06:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:01 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-TSQPg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:27:01 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Z56pRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:27:01 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-9m6sAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:27:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:04 INFO - Downloading blessings-1.6.tar.gz 06:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:27:04 INFO - Installing collected packages: blessings 06:27:04 INFO - Running setup.py install for blessings 06:27:04 INFO - Successfully installed blessings 06:27:04 INFO - Cleaning up... 06:27:04 INFO - Return code: 0 06:27:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:27:04 INFO - Reading from file tmpfile_stdout 06:27:04 INFO - Current package versions: 06:27:04 INFO - blessings == 1.6 06:27:04 INFO - blobuploader == 1.2.4 06:27:04 INFO - docopt == 0.6.1 06:27:04 INFO - manifestparser == 1.1 06:27:04 INFO - mozInstall == 1.12 06:27:04 INFO - mozcrash == 0.17 06:27:04 INFO - mozdebug == 0.1 06:27:04 INFO - mozdevice == 0.48 06:27:04 INFO - mozfile == 1.2 06:27:04 INFO - mozhttpd == 0.7 06:27:04 INFO - mozinfo == 0.9 06:27:04 INFO - mozleak == 0.1 06:27:04 INFO - mozlog == 3.2 06:27:04 INFO - moznetwork == 0.27 06:27:04 INFO - mozprocess == 0.22 06:27:04 INFO - mozprofile == 0.28 06:27:04 INFO - mozrunner == 6.11 06:27:04 INFO - mozscreenshot == 0.1 06:27:04 INFO - mozsystemmonitor == 0.3 06:27:04 INFO - moztest == 0.7 06:27:04 INFO - mozversion == 1.4 06:27:04 INFO - psutil == 3.1.1 06:27:04 INFO - requests == 1.2.3 06:27:04 INFO - wsgiref == 0.1.2 06:27:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:27:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:04 INFO - 'HOME': '/Users/cltbld', 06:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:04 INFO - 'LOGNAME': 'cltbld', 06:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:04 INFO - 'MOZ_NO_REMOTE': '1', 06:27:04 INFO - 'NO_EM_RESTART': '1', 06:27:04 INFO - 'PAGER': '/bin/cat', 06:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:04 INFO - 'PWD': '/builds/slave/test', 06:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:04 INFO - 'SHELL': '/bin/bash', 06:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:27:04 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:04 INFO - 'USER': 'cltbld', 06:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:04 INFO - 'XPC_FLAGS': '0x0', 06:27:04 INFO - 'XPC_SERVICE_NAME': '0', 06:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:27:04 INFO - Cleaning up... 06:27:04 INFO - Return code: 0 06:27:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:27:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:27:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:27:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:04 INFO - 'HOME': '/Users/cltbld', 06:27:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:04 INFO - 'LOGNAME': 'cltbld', 06:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:04 INFO - 'MOZ_NO_REMOTE': '1', 06:27:04 INFO - 'NO_EM_RESTART': '1', 06:27:04 INFO - 'PAGER': '/bin/cat', 06:27:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:04 INFO - 'PWD': '/builds/slave/test', 06:27:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:04 INFO - 'SHELL': '/bin/bash', 06:27:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:27:04 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:27:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:04 INFO - 'USER': 'cltbld', 06:27:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:04 INFO - 'XPC_FLAGS': '0x0', 06:27:04 INFO - 'XPC_SERVICE_NAME': '0', 06:27:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:05 INFO - Downloading/unpacking jsonschema 06:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:27:07 INFO - Downloading/unpacking functools32 (from jsonschema) 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27: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 06:27:07 INFO - Downloading functools32-3.2.3-2.tar.gz 06:27:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:27:08 INFO - warning: no files found matching '*.txt' 06:27:08 INFO - no previously-included directories found matching 'build' 06:27:08 INFO - no previously-included directories found matching 'dist' 06:27:08 INFO - no previously-included directories found matching '.git*' 06:27:08 INFO - Installing collected packages: jsonschema, functools32 06:27:08 INFO - Running setup.py install for functools32 06:27:08 INFO - warning: no files found matching '*.txt' 06:27:08 INFO - no previously-included directories found matching 'build' 06:27:08 INFO - no previously-included directories found matching 'dist' 06:27:08 INFO - no previously-included directories found matching '.git*' 06:27:08 INFO - Successfully installed jsonschema functools32 06:27:08 INFO - Cleaning up... 06:27:08 INFO - Return code: 0 06:27:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:27:08 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 0x10ad5aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad60570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd9d14df190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, '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.Kco8RkJduc/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.xGAHrDJmAz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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 06:27:08 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 06:27:08 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 06:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:08 INFO - 'HOME': '/Users/cltbld', 06:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:08 INFO - 'LOGNAME': 'cltbld', 06:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:08 INFO - 'MOZ_NO_REMOTE': '1', 06:27:08 INFO - 'NO_EM_RESTART': '1', 06:27:08 INFO - 'PAGER': '/bin/cat', 06:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:08 INFO - 'PWD': '/builds/slave/test', 06:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:08 INFO - 'SHELL': '/bin/bash', 06:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:27:08 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:08 INFO - 'USER': 'cltbld', 06:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:08 INFO - 'XPC_FLAGS': '0x0', 06:27:08 INFO - 'XPC_SERVICE_NAME': '0', 06:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:27:08 INFO - Downloading/unpacking flake8 06:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:27:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:27:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:27:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:27:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:11 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 06:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:27:12 INFO - no previously-included directories found matching 'docs/_build' 06:27:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:27:12 INFO - Running setup.py install for pep8 06:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:27:12 INFO - no previously-included directories found matching 'docs/_build' 06:27:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:27:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:27:12 INFO - Cleaning up... 06:27:12 INFO - Return code: 0 06:27:12 INFO - Running post-action listener: _resource_record_post_action 06:27:12 INFO - Running post-action listener: _start_resource_monitoring 06:27:12 INFO - Starting resource monitoring. 06:27:12 INFO - ##### 06:27:12 INFO - ##### Finished create-virtualenv step (success) 06:27:12 INFO - ##### 06:27:12 INFO - ##### 06:27:12 INFO - ##### Running install step. 06:27:12 INFO - ##### 06:27:12 INFO - Running pre-action listener: _resource_record_pre_action 06:27:12 INFO - Running main action method: install 06:27:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:27:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:27:13 INFO - Reading from file tmpfile_stdout 06:27:13 INFO - Detecting whether we're running mozinstall >=1.0... 06:27:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:27:13 INFO - Reading from file tmpfile_stdout 06:27:13 INFO - Output received: 06:27:13 INFO - Usage: mozinstall [options] installer 06:27:13 INFO - Options: 06:27:13 INFO - -h, --help show this help message and exit 06:27:13 INFO - -d DEST, --destination=DEST 06:27:13 INFO - Directory to install application into. [default: 06:27:13 INFO - "/builds/slave/test"] 06:27:13 INFO - --app=APP Application being installed. [default: firefox] 06:27:13 INFO - mkdir: /builds/slave/test/build/application 06:27:13 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'] 06:27:13 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 06:27:47 INFO - Reading from file tmpfile_stdout 06:27:47 INFO - Output received: 06:27:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:27:47 INFO - Running post-action listener: _resource_record_post_action 06:27:47 INFO - ##### 06:27:47 INFO - ##### Finished install step (success) 06:27:47 INFO - ##### 06:27:47 INFO - ##### 06:27:47 INFO - ##### Running run-tests step. 06:27:47 INFO - ##### 06:27:47 INFO - Running pre-action listener: _resource_record_pre_action 06:27:47 INFO - Running main action method: run_tests 06:27:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:27:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:27:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:27:47 INFO - 2016-05-24 06:27:47.565 screenresolution[1649:8154] starting screenresolution argv=screenresolution get 06:27:47 INFO - 2016-05-24 06:27:47.568 screenresolution[1649:8154] Display 0: 1600x1200x32@60 06:27:47 INFO - 2016-05-24 06:27:47.573 screenresolution[1650:8157] starting screenresolution argv=screenresolution list 06:27:47 INFO - Available Modes on Display 0 06:27:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:27:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:27:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:27:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:27:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:27:47 INFO - Intel Iris: 06:27:47 INFO - Chipset Model: Intel Iris 06:27:47 INFO - Type: GPU 06:27:47 INFO - Bus: Built-In 06:27:47 INFO - VRAM (Dynamic, Max): 1536 MB 06:27:47 INFO - Vendor: Intel (0x8086) 06:27:47 INFO - Device ID: 0x0a2e 06:27:47 INFO - Revision ID: 0x0009 06:27:47 INFO - Displays: 06:27:47 INFO - Display: 06:27:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:27:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:27:47 INFO - Display Serial Number: 1600x1200 60 06:27:47 INFO - Main Display: Yes 06:27:47 INFO - Mirror: Off 06:27:47 INFO - Online: Yes 06:27:47 INFO - Rotation: Supported 06:27:47 INFO - Return code: 0 06:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:27:47 INFO - Python 2.7.3 06:27:47 INFO - Return code: 0 06:27:47 INFO - grabbing minidump binary from tooltool 06:27:47 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')]} 06:27:47 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 0x10abfed30>, '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 0x10aa22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:27:47 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 06:27:47 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 06:27:47 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 06:27:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:27:47 INFO - Return code: 0 06:27:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:27:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:27:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:27:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:27:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:27:49 INFO - Return code: 0 06:27:49 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/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0193', '--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 06:27:49 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/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0193 --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 06:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kco8RkJduc/Render', 06:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:27:49 INFO - 'HOME': '/Users/cltbld', 06:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:27:49 INFO - 'LOGNAME': 'cltbld', 06:27:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:27:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:27:49 INFO - 'MOZ_NO_REMOTE': '1', 06:27:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:27:49 INFO - 'NO_EM_RESTART': '1', 06:27:49 INFO - 'PAGER': '/bin/cat', 06:27:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:27:49 INFO - 'PWD': '/builds/slave/test', 06:27:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:27:49 INFO - 'SHELL': '/bin/bash', 06:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xGAHrDJmAz/Listeners', 06:27:49 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 06:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:27:49 INFO - 'USER': 'cltbld', 06:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:27:49 INFO - 'XPC_FLAGS': '0x0', 06:27:49 INFO - 'XPC_SERVICE_NAME': '0', 06:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:27:49 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/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0193', '--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 06:27:49 INFO - mozversion application_buildid: 20160524051813 06:27:49 INFO - mozversion application_changeset: 74fe05a5f015f517f835f0b560b074a941103481 06:27:49 INFO - mozversion application_display_name: Nightly 06:27:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:27:49 INFO - mozversion application_name: Firefox 06:27:49 INFO - mozversion application_remotingname: firefox 06:27:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:27:49 INFO - mozversion application_vendor: Mozilla 06:27:49 INFO - mozversion application_version: 49.0a1 06:27:49 INFO - mozversion platform_buildid: 20160524051813 06:27:49 INFO - mozversion platform_changeset: 74fe05a5f015f517f835f0b560b074a941103481 06:27:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 06:27:49 INFO - mozversion platform_version: 49.0a1 06:27:49 INFO - using testdate: 1464096469 06:27:49 INFO - actual date: 1464096469 06:27:49 INFO - starting webserver on 'localhost:49279' 06:27:49 INFO - SUITE-START | Running 2 tests 06:27:49 INFO - TEST-START | dromaeo_css 06:27:49 INFO - operating with platform_type : mac_ 06:27:49 INFO - Initialising browser for dromaeo_css test... 06:27:49 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmpfi9Vrq/profile http://localhost:49279/getInfo.html) 06:27:51 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 06:27:51 INFO - PROCESS | 1668 | colorDepth:24 06:27:51 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 06:27:51 INFO - PROCESS | 1668 | __metrics 06:27:51 INFO - PROCESS | 1668 | 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" 06:27:51 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:27:52 INFO - TEST-INFO | 1668: exit 0 06:27:52 INFO - Browser initialized. 06:27:52 INFO - Running cycle 1/1 for dromaeo_css test... 06:27:52 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmpfi9Vrq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:28:04 INFO - PROCESS | 1671 | RSS: Main: 238845952 06:28:04 INFO - PROCESS | 1671 | 06:28:55 INFO - PROCESS | 1671 | 1464096535723 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] 06:31:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 06:31:07 INFO - PROCESS | 1671 | RSS: Main: 338296832 06:31:07 INFO - PROCESS | 1671 | 06:34:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 06:34:11 INFO - PROCESS | 1671 | RSS: Main: 369938432 06:34:11 INFO - PROCESS | 1671 | 06:37:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 06:37:13 INFO - PROCESS | 1671 | RSS: Main: 369872896 06:37:13 INFO - PROCESS | 1671 | 06:40:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 06:40:16 INFO - PROCESS | 1671 | RSS: Main: 369176576 06:40:16 INFO - PROCESS | 1671 | 06:43:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 06:43:20 INFO - PROCESS | 1671 | RSS: Main: 332521472 06:43:20 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 06:46:23 INFO - PROCESS | 1671 | RSS: Main: 378486784 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | __start_tp_report 06:46:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 06:46:23 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 06:46:23 INFO - PROCESS | 1671 | |i|pagename|runs| 06:46:23 INFO - PROCESS | 1671 | |0;dojo.html;3291.8847840325584;3491.6682915123056;3520.7887526748395;3522.5772907251135;3570.767900086494;5636;5667.518260947824;5695.458931401516;5707.514861236795;5783.161441590972;5681.318241810978;5743.483086522212;5792.275965504312;5797.3043234811885;5798.565107616935;5126.384833819947;5127.2052831811125;5173.818916337929;5179.119549676564;5269.76286067126;5270.868228294305;5294.682319060868;5296.09966305727;5312.07038768216;5353.9196912046355;5205.063088644042;5222.4255331913455;5234.7120908350025;5260.579153667697;5319.9202011969855;5235;5283.128283833163;5321.787128514855;5363.597730170253;5374.91937620936;5154;5193.143131383317;5201.661891977009;5262.394824595182;5286.154215325568;5601.327840659106;5622.606417550773;5639.71801409932;5692.573057020699;5703.230063941398;6138.57232748375;6138.815835524942;6197.318294987548;6216.875662486725;6250.281217659982;6235.189425374672;6292.150559674431;6309.274433440121;6339.651319177444;6349.746010159588;5905.4094590541035;5914.201582786355;5931.9703401483575;5936.406359364029;5940.316863560703;6233.1273621693;6274.498040156776;6292.685365731695;6293.433590976834;6306.527010474233;5155.536001759785;6106;6141;6183.628982260989;6268.1851359323;6305.274893387319;6328.525360597973;6335.683215839282;6342.936570634327;6370.8088757337355;6129.3870612939245;6222;6273.12176295319;6308.7476500940365;6316.2736285326855;6146.108814221912;6156.8768624627255;6224.502039836802;6229.781957631461;6321.905171422432;4989.052080104769;4992.375953005874;5006;5013.1978883379215;5039.395272567315;4421.717701866487;4437.755923424178;4467.374567560544;4475.7985890635;4548.408706868152;5624.606277560613;5640.464155905182;5695.971520142372;5702;5712.514436272963;6239.563230573816;6255.593386429959;6307.085472606446;6316.431521163117;6318.4313411793155;5704.543636509069;5705.600607957486;5705.771769129271;5711.857203569944;5727.77088916447;5291.682499050069;5370.758315875795;5421.810236641778;5440.945590544044;5453.318335208099;6347.841303967438;6351.237851457855;6357.745690172342;6364.981602943507;6398.976163813767;6144.6620435874665;6295.772324396698;6306.621602703853;6326.936730632635;6345.714442850175;6245.6252624842655;6264.498840092872;6300.558960872695;6360.300366959723;6422.614643121427;6232.127502149793;6254.312025677081;6255.249370075438;6264.2796078451565;6273.780417685359;6141.823559979257;6156.969215154075;6314.400131987454;6315.052742088724;6334.303226645157;6060.060690592965;6083.847903802441;6121.418465245794;6132.570720049732;6138.846528836815;6130.969345153424;6137.1101190328045;6146.754129834935;6155.138280640804;6165.506759459322;5809.331926828299;5916.408359164049;5924.229850119457;5932.614380065282;5980.700964951822;6045.365236650088;6144.293406258159;6151.784687535915;6162.414570615963;6167.290761562298;5798.478136967693;5863.20846685692;6020.939790602038;6025.8192254232445;6034.969825151022 06:46:23 INFO - PROCESS | 1671 | |1;ext.html;488.74340970990227;489.24411783794045;514.1259858240988;529.8463445600776;539.4093467652918;24448.75551244489;31018.914337998176;31099.222519437026;31174.37651246974;41306;40949.59050409495;43509.56490435095;43590;43599.78200109003;43624;9495.287853411004;9574.664886728966;9579.377340472882;9627.277954153431;9758.02419758022;9478.099580539838;9619.326647134703;9621.134097931219;9647.517624118802;9670.468124253162;9511.857322140173;9515.857262141073;9582.856257156114;9630.085141911542;9651.5174241288;9470.384425012353;9555.522223888778;9605;9646.276529260263;9661.71014869548;9340.439573625536;9411.717648470556;9507.096825801538;9516.571754271074;9525.476098814563;40112.59887401118;40497.79751101255;40555.39166912498;40555.98610034743;40564.18871622551;3115.376924615086;3147.417727720356;3257.8533965971587;3555.3422616815974;3816.02691313716;29196.124116276333;29577.260568485744;29632;29859.95490157834;30141.849290753624;2664.3072801071667;2688.448867982059;2695.854261938668;2788.8605569721635;2869.827810331366;484189;488708.1129188734;489621;490640;490925;34054.82972585121;59213.70393148093;59409.702951484964;59982;60199.69900150471;31876.68123318737;33083.00750977475;35108.82445587807;35340.11649708778;36076.917692469266;14381.352839122264;14624.780628290584;14793.926030369994;14819.555413337617;14835.10989340643;12554.497820087277;12648.494060237672;12652.557160499338;12664.923481504175;12669.40277243163;7814.6874125034365;7827.099883513355;7830.138684744674;7864.370850331959;7904.881426778603;3291.2759192977505;3305.93388132236;3306.5866766654167;3314.13832403572;3356.983215083909;841.6128580852754;961.9759506012266;999.0409207161061;1008.5209525475341;1056.478670714169;27493.86253068722;29271.268218294714;29796.851015745215;29913.551296730566;29961.550576741367;954.355809828363;1074.7044562745307;1079.5250089960393;1094.7700982793542;1187.7743228786676;14053.297335133408;14657.853421465863;14846.18345990981;15152.621184470476;15244;705252;711163;711946;713246;713868;56977.71511142418;58813.94151204687;59008.819823603284;59118.40881591215;59195.40804591985;1309.1228876652665;1350.1224204267305;1352.594221733476;1437.7699568069058;1457.271364317841;29286.853565732035;30041.849790750908;30318.393632127674;30408.42769980703;30713.385732285675;48866.5113348862;49801.501984981136;49805;50349.74825125851;50484.99030019453;532156;532275;532391;532787;534328;22708.432289192904;24983;25581.744182557937;25811.096611619254;25873.741262587133;45680.31479527876;45790;46135.76932115318;46775.53224467712;47607.52392476169;32819.50770738489;36329.63670363263;37629.62370376261;38334.84995450029;38356;36013;36254.637453625124;36312.63687363092;36795.08012299607;37261.5095396192;36967.63032369749;37852.05369865776;38016;38698.80650596729;39216;28231.57652635253;30760.384792304478;31153.37693246077;31755.364892702477;33017;22321.44196395103;24408.145714899892;24566.50866982686;25009;25217.621735674347 06:46:23 INFO - PROCESS | 1671 | |2;jquery.html;376.80406188781836;379.3342683590299;384.6730279262627;386.3644305118079;394.61919247925766;19516.804831951675;20260;21438.678419823715;21438.89280553597;21482.248121315737;20397.694034589455;20530.973451327412;21309;21380.893095534502;21405;4810.567048965592;5291.391489978644;6020.096985452174;6049.788257410992;6063.848403789896;5815.796447124351;5862.765489380419;5961.612495187799;6011.5491338149595;6014.0978853172155;5747.770089196448;5780.0751879699255;5833.270841144857;5867.589268751189;6010.729517171738;4644.326572646981;5656.038473459481;5942.821715348525;5963.2247807784925;5980.820575382745;5040.546350828443;5178.792848286064;5217.426083130853;5219.19102539107;5223.190405487156;13310.733785324239;13368.732625347537;24555.877220613962;25027.87374568149;25959.35101622456;1964.165229777339;2088.289981406329;2207.834412419059;2321.3500219938446;2736.7673747731465;9487.430754154773;9830.803383932353;10101.545430455648;10259.43573103479;13054.477820887154;1682.1589205397302;1699.9065051422167;1710.2902295547333;1766.8409843114052;1833.4316361927845;115966;116478.41760791227;118891;120600.39699801532;120708;11214.719632009102;11475.598354057567;11571.537138514364;11696.70758231051;11894.82157767634;14760.114393136446;14834.480793172403;14866.108033518023;14890.181040042902;14913.478028268959;8352.66589336432;8715.346349023848;8722.91277087221;8723.127687231226;8828.249598784047;8690.435121717195;8699.391042626956;8772.605232764541;8799.428037177562;8802.735917922473;4547.772611369418;4583.747893865802;4586.77066146698;4587.564181402761;4605.815767369335;1851.7223116049881;1894.952626184329;1934.3654611852962;1940.1657287366286;1961.1763059514753;869.4696235296464;905.1444538695648;954.19370631816;955.5317894231777;981.1611072532882;19737.703934440993;19885.502862428264;20667.966601669857;20711.58576828455;21105.89447052755;833.6415341403134;897.1297841094129;904.2494729374604;942.4251206763869;947.9383840050513;10217.13154381871;10655.733606659896;10887.292325998786;10900.07349375312;11045.061169800496;603956;604300;606391;621280;621441.8927905422;100130;124614;129414;134534.74770298152;137259.31370343285;952.5237381309346;967.666155176463;984.4634674102631;990.3711143423977;992.3081146644953;15950;17093.145342732565;17791.644167116843;18506;18514.259429623202;50438;52098.218526722885;53783.73108134434;54567.45432545624;54621;70747.64626176836;71308;71801;72095;72772.63613681898;7835.413435983828;8042.477238979448;8209.05595856484;11415.486303116213;12273.140880138662;12609.180403273758;12657.556985505464;12969.221846689228;13189.604311870658;13470.865291346961;11142.72143196394;11297.943510282725;11694.649160525198;11803.583569971659;11889.048876090064;11074;11286.266392684449;11349.205555611028;11653.76692466129;11679.591214307458;11181.888181118085;11516.251443656136;11899.762004759683;11930.642080737593;12025.338606376383;12274.324912129918;12282.385880706108;12344.81482777741;12577.308248046445;12623.621291361274;11651.359175245443;11669.649910502698;11855.58505452305;11892.048636108917;11975.700607484534 06:46:23 INFO - PROCESS | 1671 | |3;mootools.html;1396.441423430627;1571.4971209213054;1601.3514526616718;1810.8098649641788;1945.6011517638885;7677.347425468834;8680.869786953193;8732.95633521833;8869.60086796094;8971.596278167483;8567.700130495434;8819.029906710257;8822.647094116228;8825.382223244376;8835.425697329669;2104.3581707579183;2316.3051084674617;2346.77705617966;2365.1012615206196;2385.8329916905823;2377.738448770634;2401.7958473529684;2407.3981504623844;2408.6266628672583;2415.915442959497;2332.9650055249185;2367.4081479630095;2373.193114341124;2381.20229723043;2383.249276477904;2058.7632422271404;2278.5670722562827;2322.3381336319153;2359.622460406327;2359.681443005207;2335.7430682625068;2346.214018303853;2369.2300002499082;2373.6202207646866;2376.572217000939;8707.129287071306;8774.341924355635;8788.64845406183;8790.164934331227;8798.780030499225;5257.290265814143;5294.788208471695;5352.2239275305255;5354.303940487751;5375.838724838261;3216.4210442120407;3308.8841890533718;3389.4237979543536;3399.1502124468884;3404.3701915145775;945.5933948402185;971.1502435369052;975.2149519636689;980.5832521178542;989.4113002763344;11567.305961642329;11596.652100437;11613.477393517313;11616.767664646659;11629.94185029078;4662.860114196579;4731.4558825735485;4796.9760151199025;4818.373611430492;4827.637927155477;9924.751881202883;9955.950220248853;9957.651482198089;9959.452230127412;9980.800383992424;5085.74571271435;5106.080905437057;5152.742362881841;5179.04187725272;5193.3767947845745;4430.955690443118;4589.242774942164;4800.423949126058;4938.8765280868265;4940.209566469348;3868.593797651206;3944.9091981249985;3983.92032159361;4015.77913214776;4045.2314060328445;1263.8357013588175;1271.3007845684836;1272.7136394311206;1277.1251692590606;1280;825.7316372179066;829.9128591497927;876.1063715010654;901.8016036471965;910.7222297199418;5715.942840571541;5716.971415142981;5750.079987202028;5856.4143585641095;5888.32182827892;425.3874420833989;435.5492065711973;440.25816499198953;443.40362212823703;444.5265791931582;3274.459714147139;3324.25204329024;3492.511048453219;3927.19492504036;3979.7413168143976;10367.63713270032;10655.946720266504;10771.676849694522;10897.891021089847;10909.34543927367;10849.349039057683;10858.565657373774;10870.293430926964;10876.293040952312;10890.782184356269;7726.227377262228;8147.3482121431325;8172.387070969582;8176.918230817735;8215.876761848576;5714.285714285715;5868.501177399969;5942.019566771434;5991;6020.397960203944;8089.271965523131;10011.249156313452;10065.295429319878;10066.295359324777;10109;9652.613895444385;9839.212862970802;10221.437820919624;10407.531661075118;10411.219158562986;6700.195976482853;6930.06444130036;7109.608971506616;7165.928340716526;7435.219301973215;8288.21261980111;8327.750167494985;8410.495370277802;8479.830403391774;8492.150784921458;7177.641117943977;7324.670389832364;7441.962790186231;7599.810004749705;7605.315521603082;7169.5698258104685;7275.163356214103;7292.708291668274;7364.815879602838;7425.925740742523;6804.4216241620015;6837.965810170917;6862.553933994274;6894;6894.8621027580175;4749.810007599795;4781;4829.517048295143;4902.534259245366;5015.072211640858;4417.293233082692;4447.688661793773;4491.977540112279;4499.280115181556;4525.547445255448 06:46:23 INFO - PROCESS | 1671 | |4;prototype.html;302.2926352335535;307.04355931274085;309.5480598326444;313.7787859559011;317.5189587774523;4616.1460129875995;4673.270889770785;5199.6360254782185;5261.210818377245;5263.842084737459;4397.05463325385;5025.698458092508;5174.2238664200295;5216.15719432909;5230.267762513242;3443.7589368744198;3523.3481805865867;4000.9399859002137;4036.3945408188715;4125.587441255881;3403.4724617684296;4008.474660963589;4016.5782592827773;4021.1153546219784;4022.597740225983;3552.467129930505;3984.8804535863824;3995.9400608990886;4007.2185923744883;4023.7585744855255;3760.8529398533437;3882.7087968402484;3922.8430862765454;3941.054147004727;4031.314676505001;2984.567237750514;3231.7737758356925;3485.9651403485896;3503.929921401583;3530.329237444903;4316.417283666696;4978.00439912019;5027.698338099689;5053.191489361721;5078.4413714491375;4058.715889887709;4201.075763332093;4522.9547704522865;4601.746903920283;4608.28571571407;4693.389859318267;4715.269133284346;4804.279358096279;5488.286522752017;5504.614676972614;3267.673232676737;3791.7487229214294;3799.2211596622624;3817.2747178036084;3829.5404551453726;4881.926771098365;4914;5473.917891231635;5902.704864756745;5995.37048609894;3193.8083714977174;3375.9493607595905;3685.649863263039;3795.981020094937;3808.638179372955;3587.2287458196615;3866.2847373235477;3876.476675648751;3898.9805050975133;3936.1340505088833;4205.411242426063;4522.525134860858;4531;4549.272116461417;4553.977230113895;5093.694378337312;5407.45925407464;5493.533049690741;5568.749406276727;5634.802781902614;1062.5484169228203;1172.6104566089211;1236.6351926181799;1296.7665820152463;1319.0436933223527;653.9210303000087;768.4812751392899;796.3907610677836;810.2181394953898;818.4884447220487;4905.901881962341;4924.285978533093;5106.4382917879;5115.335006449212;5148.9742551287;4888;5044.571211446994;5359.678419294854;5550.666959982414;5708.229389032511;4885.389326334208;5141.38303403594;5145.665531740425;5150.768215430239;5173.818916337986;2956.600858884066;2968.302448924544;2981.3292009297734;2997.6103106596;3006.128222815359;5108.897822043538;5220.712860792617;5315.813946511854;5968.552358573038;6010.248718910137;4864.270359446112;5422.376426710979;5692.459216374438;5727.684977326204;5832.504237139806;5163.948360516347;5761.423857614289;5842.415758424124;5884.9705751470965;5917.05327147663;5077.365329333833;5210.837502436598;5660.490555849993;5670.319561652628;5670.603057785916;4931.852044438672;5570.5265052470995;5579.7489112990825;5614.9719251403485;5636.1827535008015;5250.868728281824;5330.466953304794;5496.450354964471;5782.8434313136695;5913.763449461973;3570.785752854837;3641.617630148902;3763.3844478087467;3785;3804.410316400962;4345.783180709406;4437.467503899424;5417.133258678593;5557.72211389437;5661.999140455565;4899.525739501529;5440.6735595864375;5451.7274136292235;5473.8357849264585;5506.439505629741;4541.795619197078;4698.718076915396;5447.400785913467;5508.834734957958;5512.834614961557;4913.361263035782;5194.844154675366;5426.484483974016;5482.424345443671;5511.905475919285;4802.975985120052;5184.507471790174;5418.647787893774;5454.181872718965;5464.3442786865835;4650.9767451162525;4827.541383568555;5396.463662078817;5418.8620389717025;5422.3732880136595 06:46:23 INFO - PROCESS | 1671 | |5;yui.html;646.8124243969248;669.9765508207214;670.3363669966732;688.2841844481736;696.8815301398762;12550.623481295575;13282.867171328284;14519.128852268857;15151.924240378796;15184.54446366608;13617.795733064011;13973.720525589508;13997.580072597813;14054;14108;719.7984564321972;744.9515781474214;887.7780554861284;890.5725251879102;892.8169725206317;700.4186525184085;723.5043994863536;862.1249431826701;892.5537231384308;894.5437826708393;893.8033632600817;899.6176624934409;924.8797656304671;931.580788645109;934.9252059835197;723.6417973103295;919.7608621758324;931.5481991234246;931.683227702581;934.4299977014016;659.426299119764;680.2653134614653;680.7685386968454;682.5665702279089;684.6302996381947;14911.552653420415;15088.924555377263;15099;15151.318190681446;15155;11939.820902686466;11962.461689224006;12001;12220.2667839929;12355.691107722289;9895.703128906145;10839.512221949952;10869.130469562417;10954.780904381947;11315.9257488846;4007.238624661305;4014.3139881249654;4041.6362527372385;4163.084121493297;4266.765327906964;83137.16862831355;83810.58094709548;83917.16082839154;84546.15453845444;85202;9349.205317548085;9377.531123443801;9480.857787133198;9582.616695332108;9739.318247722733;20138.295159669342;20682;20688.793112068986;20700.275490358068;20701.79298207029;7374.483786134919;7433.070866141732;7445.404367650575;7542.962285188649;7564.9243507565325;3835.7890316032326;3922.114185493042;3967.1272320089533;3990.20195960809;4034.1326614777963;3328.184594774296;3372.1232479555492;3454.6890779829937;3457.8789742358895;3461.671141241578;616.5098746496546;618.6133666458463;624.2664868779166;625.7246811402969;630.7666163519435;511.8976204759063;514.5137844736688;533.2055237695805;571.9742611582489;572.8281515545319;7610.467267291237;7789.766307010798;7884.724034658874;8014.799630009296;8143.015740850907;474.5657723183249;488.6082625547923;498.048726931559;498.6213120721209;596.2844586496221;3454.2573346730574;3534.9646503534636;3712.925741485209;3729.4965179700935;3769.095417099876;70886;89013;89583.55208223917;89661;93041.5347923256;78387.60806195933;79909.80135297975;80211.7968230477;82420;84703;71414.99999999897;72141;72421;72558.27441725515;72699.27300726925;7169.7849064528145;7197.244289349753;7220.963895180701;7296.452766042462;7323.194448610755;45014.549854502344;45292;45352;45811;47711.52288477071;53197;56922.7153864228;57306;60577.788444229984;62122;8781.780455488664;9329.486878221764;9435.05649435051;9473.573689183866;10091.848622270525;10338.500096970094;10377.907410446549;10549.947250264007;10614.522346494272;10700.143988481059;9691.030896910535;9747.463889486145;9785.559649815634;9866.851997219905;9955.601775928882;8300.501969881827;9443.527823608929;9991.450470224207;10035.598576057168;10132.74668133273;9670.70987870365;9756.902430975882;9832.16426603718;10067.345622534513;10099;8272.586370681562;8323.334133269445;8619.310455163697;8668.479891206547;8843.380963332762;8279.898395218965;8375.581220938806;8706.259967902799;8901.688440904538;8904.109589041043 06:46:23 INFO - PROCESS | 1671 | __end_tp_report 06:46:23 INFO - PROCESS | 1671 | __start_cc_report 06:46:23 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1100 06:46:23 INFO - PROCESS | 1671 | __end_cc_report 06:46:23 INFO - PROCESS | 1671 | __startTimestamp1464097583767__endTimestamp 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | ------- Summary: start ------- 06:46:23 INFO - PROCESS | 1671 | Number of tests: 6 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5775.81 Median:5980.70 stddev:627.61 (10.5%) stddev-sans-first:598.52 06:46:23 INFO - PROCESS | 1671 | Values: 3291.9 3491.7 3520.8 3522.6 3570.8 5636.0 5667.5 5695.5 5707.5 5783.2 5681.3 5743.5 5792.3 5797.3 5798.6 5126.4 5127.2 5173.8 5179.1 5269.8 5270.9 5294.7 5296.1 5312.1 5353.9 5205.1 5222.4 5234.7 5260.6 5319.9 5235.0 5283.1 5321.8 5363.6 5374.9 5154.0 5193.1 5201.7 5262.4 5286.2 5601.3 5622.6 5639.7 5692.6 5703.2 6138.6 6138.8 6197.3 6216.9 6250.3 6235.2 6292.2 6309.3 6339.7 6349.7 5905.4 5914.2 5932.0 5936.4 5940.3 6233.1 6274.5 6292.7 6293.4 6306.5 5155.5 6106.0 6141.0 6183.6 6268.2 6305.3 6328.5 6335.7 6342.9 6370.8 6129.4 6222.0 6273.1 6308.7 6316.3 6146.1 6156.9 6224.5 6229.8 6321.9 4989.1 4992.4 5006.0 5013.2 5039.4 4421.7 4437.8 4467.4 4475.8 4548.4 5624.6 5640.5 5696.0 5702.0 5712.5 6239.6 6255.6 6307.1 6316.4 6318.4 5704.5 5705.6 5705.8 5711.9 5727.8 5291.7 5370.8 5421.8 5440.9 5453.3 6347.8 6351.2 6357.7 6365.0 6399.0 6144.7 6295.8 6306.6 6326.9 6345.7 6245.6 6264.5 6300.6 6360.3 6422.6 6232.1 6254.3 6255.2 6264.3 6273.8 6141.8 6157.0 6314.4 6315.1 6334.3 6060.1 6083.8 6121.4 6132.6 6138.8 6131.0 6137.1 6146.8 6155.1 6165.5 5809.3 5916.4 5924.2 5932.6 5980.7 6045.4 6144.3 6151.8 6162.4 6167.3 5798.5 5863.2 6020.9 6025.8 6035.0 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:68992.62 Median:20916.01 stddev:157148.80 (751.3%) stddev-sans-first:157505.47 06:46:23 INFO - PROCESS | 1671 | Values: 488.7 489.2 514.1 529.8 539.4 24448.8 31018.9 31099.2 31174.4 41306.0 40949.6 43509.6 43590.0 43599.8 43624.0 9495.3 9574.7 9579.4 9627.3 9758.0 9478.1 9619.3 9621.1 9647.5 9670.5 9511.9 9515.9 9582.9 9630.1 9651.5 9470.4 9555.5 9605.0 9646.3 9661.7 9340.4 9411.7 9507.1 9516.6 9525.5 40112.6 40497.8 40555.4 40556.0 40564.2 3115.4 3147.4 3257.9 3555.3 3816.0 29196.1 29577.3 29632.0 29860.0 30141.8 2664.3 2688.4 2695.9 2788.9 2869.8 484189.0 488708.1 489621.0 490640.0 490925.0 34054.8 59213.7 59409.7 59982.0 60199.7 31876.7 33083.0 35108.8 35340.1 36076.9 14381.4 14624.8 14793.9 14819.6 14835.1 12554.5 12648.5 12652.6 12664.9 12669.4 7814.7 7827.1 7830.1 7864.4 7904.9 3291.3 3305.9 3306.6 3314.1 3357.0 841.6 962.0 999.0 1008.5 1056.5 27493.9 29271.3 29796.9 29913.6 29961.6 954.4 1074.7 1079.5 1094.8 1187.8 14053.3 14657.9 14846.2 15152.6 15244.0 705252.0 711163.0 711946.0 713246.0 713868.0 56977.7 58813.9 59008.8 59118.4 59195.4 1309.1 1350.1 1352.6 1437.8 1457.3 29286.9 30041.8 30318.4 30408.4 30713.4 48866.5 49801.5 49805.0 50349.7 50485.0 532156.0 532275.0 532391.0 532787.0 534328.0 22708.4 24983.0 25581.7 25811.1 25873.7 45680.3 45790.0 46135.8 46775.5 47607.5 32819.5 36329.6 37629.6 38334.8 38356.0 36013.0 36254.6 36312.6 36795.1 37261.5 36967.6 37852.1 38016.0 38698.8 39216.0 28231.6 30760.4 31153.4 31755.4 33017.0 22321.4 24408.1 24566.5 25009.0 25217.6 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35245.35 Median:21421.84 stddev:101807.85 (475.3%) stddev-sans-first:102059.78 06:46:23 INFO - PROCESS | 1671 | Values: 376.8 379.3 384.7 386.4 394.6 19516.8 20260.0 21438.7 21438.9 21482.2 20397.7 20531.0 21309.0 21380.9 21405.0 4810.6 5291.4 6020.1 6049.8 6063.8 5815.8 5862.8 5961.6 6011.5 6014.1 5747.8 5780.1 5833.3 5867.6 6010.7 4644.3 5656.0 5942.8 5963.2 5980.8 5040.5 5178.8 5217.4 5219.2 5223.2 13310.7 13368.7 24555.9 25027.9 25959.4 1964.2 2088.3 2207.8 2321.4 2736.8 9487.4 9830.8 10101.5 10259.4 13054.5 1682.2 1699.9 1710.3 1766.8 1833.4 115966.0 116478.4 118891.0 120600.4 120708.0 11214.7 11475.6 11571.5 11696.7 11894.8 14760.1 14834.5 14866.1 14890.2 14913.5 8352.7 8715.3 8722.9 8723.1 8828.2 8690.4 8699.4 8772.6 8799.4 8802.7 4547.8 4583.7 4586.8 4587.6 4605.8 1851.7 1895.0 1934.4 1940.2 1961.2 869.5 905.1 954.2 955.5 981.2 19737.7 19885.5 20668.0 20711.6 21105.9 833.6 897.1 904.2 942.4 947.9 10217.1 10655.7 10887.3 10900.1 11045.1 603956.0 604300.0 606391.0 621280.0 621441.9 100130.0 124614.0 129414.0 134534.7 137259.3 952.5 967.7 984.5 990.4 992.3 15950.0 17093.1 17791.6 18506.0 18514.3 50438.0 52098.2 53783.7 54567.5 54621.0 70747.6 71308.0 71801.0 72095.0 72772.6 7835.4 8042.5 8209.1 11415.5 12273.1 12609.2 12657.6 12969.2 13189.6 13470.9 11142.7 11297.9 11694.6 11803.6 11889.0 11074.0 11286.3 11349.2 11653.8 11679.6 11181.9 11516.3 11899.8 11930.6 12025.3 12274.3 12282.4 12344.8 12577.3 12623.6 11651.4 11669.6 11855.6 11892.0 11975.7 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5640.31 Median:4823.01 stddev:3229.55 (67.0%) stddev-sans-first:3222.87 06:46:23 INFO - PROCESS | 1671 | Values: 1396.4 1571.5 1601.4 1810.8 1945.6 7677.3 8680.9 8733.0 8869.6 8971.6 8567.7 8819.0 8822.6 8825.4 8835.4 2104.4 2316.3 2346.8 2365.1 2385.8 2377.7 2401.8 2407.4 2408.6 2415.9 2333.0 2367.4 2373.2 2381.2 2383.2 2058.8 2278.6 2322.3 2359.6 2359.7 2335.7 2346.2 2369.2 2373.6 2376.6 8707.1 8774.3 8788.6 8790.2 8798.8 5257.3 5294.8 5352.2 5354.3 5375.8 3216.4 3308.9 3389.4 3399.2 3404.4 945.6 971.2 975.2 980.6 989.4 11567.3 11596.7 11613.5 11616.8 11629.9 4662.9 4731.5 4797.0 4818.4 4827.6 9924.8 9956.0 9957.7 9959.5 9980.8 5085.7 5106.1 5152.7 5179.0 5193.4 4431.0 4589.2 4800.4 4938.9 4940.2 3868.6 3944.9 3983.9 4015.8 4045.2 1263.8 1271.3 1272.7 1277.1 1280.0 825.7 829.9 876.1 901.8 910.7 5715.9 5717.0 5750.1 5856.4 5888.3 425.4 435.5 440.3 443.4 444.5 3274.5 3324.3 3492.5 3927.2 3979.7 10367.6 10655.9 10771.7 10897.9 10909.3 10849.3 10858.6 10870.3 10876.3 10890.8 7726.2 8147.3 8172.4 8176.9 8215.9 5714.3 5868.5 5942.0 5991.0 6020.4 8089.3 10011.2 10065.3 10066.3 10109.0 9652.6 9839.2 10221.4 10407.5 10411.2 6700.2 6930.1 7109.6 7165.9 7435.2 8288.2 8327.8 8410.5 8479.8 8492.2 7177.6 7324.7 7442.0 7599.8 7605.3 7169.6 7275.2 7292.7 7364.8 7425.9 6804.4 6838.0 6862.6 6894.0 6894.9 4749.8 4781.0 4829.5 4902.5 5015.1 4417.3 4447.7 4492.0 4499.3 4525.5 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4406.79 Median:4913.68 stddev:1356.52 (27.6%) stddev-sans-first:1324.88 06:46:23 INFO - PROCESS | 1671 | Values: 302.3 307.0 309.5 313.8 317.5 4616.1 4673.3 5199.6 5261.2 5263.8 4397.1 5025.7 5174.2 5216.2 5230.3 3443.8 3523.3 4000.9 4036.4 4125.6 3403.5 4008.5 4016.6 4021.1 4022.6 3552.5 3984.9 3995.9 4007.2 4023.8 3760.9 3882.7 3922.8 3941.1 4031.3 2984.6 3231.8 3486.0 3503.9 3530.3 4316.4 4978.0 5027.7 5053.2 5078.4 4058.7 4201.1 4523.0 4601.7 4608.3 4693.4 4715.3 4804.3 5488.3 5504.6 3267.7 3791.7 3799.2 3817.3 3829.5 4881.9 4914.0 5473.9 5902.7 5995.4 3193.8 3375.9 3685.6 3796.0 3808.6 3587.2 3866.3 3876.5 3899.0 3936.1 4205.4 4522.5 4531.0 4549.3 4554.0 5093.7 5407.5 5493.5 5568.7 5634.8 1062.5 1172.6 1236.6 1296.8 1319.0 653.9 768.5 796.4 810.2 818.5 4905.9 4924.3 5106.4 5115.3 5149.0 4888.0 5044.6 5359.7 5550.7 5708.2 4885.4 5141.4 5145.7 5150.8 5173.8 2956.6 2968.3 2981.3 2997.6 3006.1 5108.9 5220.7 5315.8 5968.6 6010.2 4864.3 5422.4 5692.5 5727.7 5832.5 5163.9 5761.4 5842.4 5885.0 5917.1 5077.4 5210.8 5660.5 5670.3 5670.6 4931.9 5570.5 5579.7 5615.0 5636.2 5250.9 5330.5 5496.5 5782.8 5913.8 3570.8 3641.6 3763.4 3785.0 3804.4 4345.8 4437.5 5417.1 5557.7 5662.0 4899.5 5440.7 5451.7 5473.8 5506.4 4541.8 4698.7 5447.4 5508.8 5512.8 4913.4 5194.8 5426.5 5482.4 5511.9 4803.0 5184.5 5418.6 5454.2 5464.3 4651.0 4827.5 5396.5 5418.9 5422.4 06:46:23 INFO - PROCESS | 1671 | 06:46:23 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17624.87 Median:683.60 stddev:25335.03 (3706.1%) stddev-sans-first:25374.03 06:46:23 INFO - PROCESS | 1671 | Values: 646.8 670.0 670.3 688.3 696.9 12550.6 13282.9 14519.1 15151.9 15184.5 13617.8 13973.7 13997.6 14054.0 14108.0 719.8 745.0 887.8 890.6 892.8 700.4 723.5 862.1 892.6 894.5 893.8 899.6 924.9 931.6 934.9 723.6 919.8 931.5 931.7 934.4 659.4 680.3 680.8 682.6 684.6 14911.6 15088.9 15099.0 15151.3 15155.0 11939.8 11962.5 12001.0 12220.3 12355.7 9895.7 10839.5 10869.1 10954.8 11315.9 4007.2 4014.3 4041.6 4163.1 4266.8 83137.2 83810.6 83917.2 84546.2 85202.0 9349.2 9377.5 9480.9 9582.6 9739.3 20138.3 20682.0 20688.8 20700.3 20701.8 7374.5 7433.1 7445.4 7543.0 7564.9 3835.8 3922.1 3967.1 3990.2 4034.1 3328.2 3372.1 3454.7 3457.9 3461.7 616.5 618.6 624.3 625.7 630.8 511.9 514.5 533.2 572.0 572.8 7610.5 7789.8 7884.7 8014.8 8143.0 474.6 488.6 498.0 498.6 596.3 3454.3 3535.0 3712.9 3729.5 3769.1 70886.0 89013.0 89583.6 89661.0 93041.5 78387.6 79909.8 80211.8 82420.0 84703.0 71415.0 72141.0 72421.0 72558.3 72699.3 7169.8 7197.2 7221.0 7296.5 7323.2 45014.5 45292.0 45352.0 45811.0 47711.5 53197.0 56922.7 57306.0 60577.8 62122.0 8781.8 9329.5 9435.1 9473.6 10091.8 10338.5 10377.9 10549.9 10614.5 10700.1 9691.0 9747.5 9785.6 9866.9 9955.6 8300.5 9443.5 9991.5 10035.6 10132.7 9670.7 9756.9 9832.2 10067.3 10099.0 8272.6 8323.3 8619.3 8668.5 8843.4 8279.9 8375.6 8706.3 8901.7 8904.1 06:46:23 INFO - PROCESS | 1671 | -------- Summary: end -------- 06:46:23 INFO - PROCESS | 1671 | 06:46:24 INFO - TEST-INFO | 1671: exit 0 06:46:24 INFO - TEST-OK | dromaeo_css | took 1115100ms 06:46:24 INFO - TEST-START | kraken 06:46:24 INFO - operating with platform_type : mac_ 06:46:24 INFO - Initialising browser for kraken test... 06:46:24 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmpKjsLP3/profile http://localhost:49279/getInfo.html) 06:46:26 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 06:46:26 INFO - PROCESS | 1678 | colorDepth:24 06:46:26 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 06:46:26 INFO - PROCESS | 1678 | __metrics 06:46:26 INFO - PROCESS | 1678 | 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" 06:46:26 ERROR - PROCESS | 1678 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:46:27 INFO - TEST-INFO | 1678: exit 0 06:46:27 INFO - Browser initialized. 06:46:27 INFO - Running cycle 1/1 for kraken test... 06:46:27 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmpKjsLP3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:46:39 INFO - PROCESS | 1680 | RSS: Main: 238886912 06:46:39 INFO - PROCESS | 1680 | 06:46:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 06:46:43 INFO - PROCESS | 1680 | RSS: Main: 287830016 06:46:43 INFO - PROCESS | 1680 | 06:46:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 06:46:46 INFO - PROCESS | 1680 | RSS: Main: 296108032 06:46:46 INFO - PROCESS | 1680 | 06:46:50 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 06:46:50 INFO - PROCESS | 1680 | RSS: Main: 384471040 06:46:50 INFO - PROCESS | 1680 | 06:46:52 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 06:46:53 INFO - PROCESS | 1680 | RSS: Main: 284274688 06:46:53 INFO - PROCESS | 1680 | 06:46:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:46:56 INFO - PROCESS | 1680 | RSS: Main: 288210944 06:46:56 INFO - PROCESS | 1680 | 06:46:59 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 06:47:00 INFO - PROCESS | 1680 | RSS: Main: 328671232 06:47:00 INFO - PROCESS | 1680 | 06:47:03 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 06:47:03 INFO - PROCESS | 1680 | RSS: Main: 331325440 06:47:03 INFO - PROCESS | 1680 | 06:47:06 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 06:47:07 INFO - PROCESS | 1680 | RSS: Main: 326819840 06:47:07 INFO - PROCESS | 1680 | 06:47:09 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:47:09 INFO - PROCESS | 1680 | RSS: Main: 316485632 06:47:09 INFO - PROCESS | 1680 | 06:47:11 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:47:12 INFO - PROCESS | 1680 | RSS: Main: 323330048 06:47:12 INFO - PROCESS | 1680 | 06:47:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:47:15 INFO - PROCESS | 1680 | RSS: Main: 326443008 06:47:15 INFO - PROCESS | 1680 | 06:47:18 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:47:19 INFO - PROCESS | 1680 | RSS: Main: 330924032 06:47:19 INFO - PROCESS | 1680 | 06:47:22 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:47:22 INFO - PROCESS | 1680 | RSS: Main: 328646656 06:47:22 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 06:47:24 INFO - PROCESS | 1680 | RSS: Main: 375463936 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | __start_tp_report 06:47:24 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 06:47:24 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 06:47:24 INFO - PROCESS | 1680 | |i|pagename|runs| 06:47:24 INFO - PROCESS | 1680 | |0;ai-astar;112;107;112;112;112;112;113;113;115;110 06:47:24 INFO - PROCESS | 1680 | |1;audio-beat-detection;155;118;118;201;116;118;138;116;155;119 06:47:24 INFO - PROCESS | 1680 | |2;audio-dft;183;184;188;183;184;185;182;183;183;186 06:47:24 INFO - PROCESS | 1680 | |3;audio-fft;80;57;61;120;57;58;66;57;97;56 06:47:24 INFO - PROCESS | 1680 | |4;audio-oscillator;99;102;199;99;89;77;105;80;78;109 06:47:24 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;91;88;92;89;91;88;91;88;91;89 06:47:24 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;95;96;96;95;107;149;96;95;96 06:47:24 INFO - PROCESS | 1680 | |7;imaging-desaturate;85;84;84;85;83;85;83;84;85;95 06:47:24 INFO - PROCESS | 1680 | |8;json-parse-financial;56;57;57;55;57;57;56;121;58;55 06:47:24 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;66;72;73;76;71;72;70;70;71;70 06:47:24 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;84;85;87;85;85;84;80;84;85;87 06:47:24 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;143;145;144;150;137;137;133;136;211;140 06:47:24 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;157;162;167;166;151;203;227;167;163;167 06:47:24 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;67;67;68;68;66;66;66;67;67;65 06:47:24 INFO - PROCESS | 1680 | __end_tp_report 06:47:24 INFO - PROCESS | 1680 | __start_cc_report 06:47:24 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,2267 06:47:24 INFO - PROCESS | 1680 | __end_cc_report 06:47:24 INFO - PROCESS | 1680 | __startTimestamp1464097644698__endTimestamp 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | ------- Summary: start ------- 06:47:24 INFO - PROCESS | 1680 | Number of tests: 14 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:111.80 Median:112.00 stddev:2.10 (1.9%) stddev-sans-first:2.22 06:47:24 INFO - PROCESS | 1680 | Values: 112.0 107.0 112.0 112.0 112.0 112.0 113.0 113.0 115.0 110.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:135.40 Median:128.50 stddev:27.91 (21.7%) stddev-sans-first:28.68 06:47:24 INFO - PROCESS | 1680 | Values: 155.0 118.0 118.0 201.0 116.0 118.0 138.0 116.0 155.0 119.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.00 stddev:1.79 (1.0%) stddev-sans-first:1.86 06:47:24 INFO - PROCESS | 1680 | Values: 183.0 184.0 188.0 183.0 184.0 185.0 182.0 183.0 183.0 186.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:70.90 Median:59.50 stddev:21.77 (36.6%) stddev-sans-first:22.84 06:47:24 INFO - PROCESS | 1680 | Values: 80.0 57.0 61.0 120.0 57.0 58.0 66.0 57.0 97.0 56.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:103.70 Median:79.00 stddev:35.45 (44.9%) stddev-sans-first:37.56 06:47:24 INFO - PROCESS | 1680 | Values: 99.0 102.0 199.0 99.0 89.0 77.0 105.0 80.0 78.0 109.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:91.00 stddev:1.55 (1.7%) stddev-sans-first:1.58 06:47:24 INFO - PROCESS | 1680 | Values: 91.0 88.0 92.0 89.0 91.0 88.0 91.0 88.0 91.0 89.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:95.50 stddev:16.91 (17.7%) stddev-sans-first:17.75 06:47:24 INFO - PROCESS | 1680 | Values: 95.0 95.0 96.0 96.0 95.0 107.0 149.0 96.0 95.0 96.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:3.50 (4.1%) stddev-sans-first:3.71 06:47:24 INFO - PROCESS | 1680 | Values: 85.0 84.0 84.0 85.0 83.0 85.0 83.0 84.0 85.0 95.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:57.00 stddev:20.44 (35.9%) stddev-sans-first:21.52 06:47:24 INFO - PROCESS | 1680 | Values: 56.0 57.0 57.0 55.0 57.0 57.0 56.0 121.0 58.0 55.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:2.56 (3.6%) stddev-sans-first:1.94 06:47:24 INFO - PROCESS | 1680 | Values: 66.0 72.0 73.0 76.0 71.0 72.0 70.0 70.0 71.0 70.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:1.96 (2.3%) stddev-sans-first:2.06 06:47:24 INFO - PROCESS | 1680 | Values: 84.0 85.0 87.0 85.0 85.0 84.0 80.0 84.0 85.0 87.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:143.50 stddev:22.84 (15.9%) stddev-sans-first:24.17 06:47:24 INFO - PROCESS | 1680 | Values: 143.0 145.0 144.0 150.0 137.0 137.0 133.0 136.0 211.0 140.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.00 Median:167.00 stddev:23.41 (14.0%) stddev-sans-first:24.11 06:47:24 INFO - PROCESS | 1680 | Values: 157.0 162.0 167.0 166.0 151.0 203.0 227.0 167.0 163.0 167.0 06:47:24 INFO - PROCESS | 1680 | 06:47:24 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 06:47:24 INFO - PROCESS | 1680 | Values: 67.0 67.0 68.0 68.0 66.0 66.0 66.0 67.0 67.0 65.0 06:47:24 INFO - PROCESS | 1680 | -------- Summary: end -------- 06:47:24 INFO - PROCESS | 1680 | 06:47:25 INFO - TEST-INFO | 1680: exit 0 06:47:25 INFO - TEST-OK | kraken | took 61313ms 06:47:25 INFO - SUITE-END | took 1176s 06:47:25 INFO - Completed test suite (00:19:36) 06:47:25 INFO - javascript benchmark 06:47:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3291.8847840325584, 3491.6682915123056, 3520.7887526748395, 3522.5772907251135, 3570.767900086494, 5636.0, 5667.518260947824, 5695.458931401516, 5707.514861236795, 5783.161441590972, 5681.318241810978, 5743.483086522212, 5792.275965504312, 5797.3043234811885, 5798.565107616935, 5126.384833819947, 5127.2052831811125, 5173.818916337929, 5179.119549676564, 5269.76286067126, 5270.868228294305, 5294.682319060868, 5296.09966305727, 5312.07038768216, 5353.9196912046355, 5205.063088644042, 5222.4255331913455, 5234.7120908350025, 5260.579153667697, 5319.9202011969855, 5235.0, 5283.128283833163, 5321.787128514855, 5363.597730170253, 5374.91937620936, 5154.0, 5193.143131383317, 5201.661891977009, 5262.394824595182, 5286.154215325568, 5601.327840659106, 5622.606417550773, 5639.71801409932, 5692.573057020699, 5703.230063941398, 6138.57232748375, 6138.815835524942, 6197.318294987548, 6216.875662486725, 6250.281217659982, 6235.189425374672, 6292.150559674431, 6309.274433440121, 6339.651319177444, 6349.746010159588, 5905.4094590541035, 5914.201582786355, 5931.9703401483575, 5936.406359364029, 5940.316863560703, 6233.1273621693, 6274.498040156776, 6292.685365731695, 6293.433590976834, 6306.527010474233, 5155.536001759785, 6106.0, 6141.0, 6183.628982260989, 6268.1851359323, 6305.274893387319, 6328.525360597973, 6335.683215839282, 6342.936570634327, 6370.8088757337355, 6129.3870612939245, 6222.0, 6273.12176295319, 6308.7476500940365, 6316.2736285326855, 6146.108814221912, 6156.8768624627255, 6224.502039836802, 6229.781957631461, 6321.905171422432, 4989.052080104769, 4992.375953005874, 5006.0, 5013.1978883379215, 5039.395272567315, 4421.717701866487, 4437.755923424178, 4467.374567560544, 4475.7985890635, 4548.408706868152, 5624.606277560613, 5640.464155905182, 5695.971520142372, 5702.0, 5712.514436272963, 6239.563230573816, 6255.593386429959, 6307.085472606446, 6316.431521163117, 6318.4313411793155, 5704.543636509069, 5705.600607957486, 5705.771769129271, 5711.857203569944, 5727.77088916447, 5291.682499050069, 5370.758315875795, 5421.810236641778, 5440.945590544044, 5453.318335208099, 6347.841303967438, 6351.237851457855, 6357.745690172342, 6364.981602943507, 6398.976163813767, 6144.6620435874665, 6295.772324396698, 6306.621602703853, 6326.936730632635, 6345.714442850175, 6245.6252624842655, 6264.498840092872, 6300.558960872695, 6360.300366959723, 6422.614643121427, 6232.127502149793, 6254.312025677081, 6255.249370075438, 6264.2796078451565, 6273.780417685359, 6141.823559979257, 6156.969215154075, 6314.400131987454, 6315.052742088724, 6334.303226645157, 6060.060690592965, 6083.847903802441, 6121.418465245794, 6132.570720049732, 6138.846528836815, 6130.969345153424, 6137.1101190328045, 6146.754129834935, 6155.138280640804, 6165.506759459322, 5809.331926828299, 5916.408359164049, 5924.229850119457, 5932.614380065282, 5980.700964951822, 6045.365236650088, 6144.293406258159, 6151.784687535915, 6162.414570615963, 6167.290761562298, 5798.478136967693, 5863.20846685692, 6020.939790602038, 6025.8192254232445, 6034.969825151022], "value": 5736.697929052202, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.74340970990227, 489.24411783794045, 514.1259858240988, 529.8463445600776, 539.4093467652918, 24448.75551244489, 31018.914337998176, 31099.222519437026, 31174.37651246974, 41306.0, 40949.59050409495, 43509.56490435095, 43590.0, 43599.78200109003, 43624.0, 9495.287853411004, 9574.664886728966, 9579.377340472882, 9627.277954153431, 9758.02419758022, 9478.099580539838, 9619.326647134703, 9621.134097931219, 9647.517624118802, 9670.468124253162, 9511.857322140173, 9515.857262141073, 9582.856257156114, 9630.085141911542, 9651.5174241288, 9470.384425012353, 9555.522223888778, 9605.0, 9646.276529260263, 9661.71014869548, 9340.439573625536, 9411.717648470556, 9507.096825801538, 9516.571754271074, 9525.476098814563, 40112.59887401118, 40497.79751101255, 40555.39166912498, 40555.98610034743, 40564.18871622551, 3115.376924615086, 3147.417727720356, 3257.8533965971587, 3555.3422616815974, 3816.02691313716, 29196.124116276333, 29577.260568485744, 29632.0, 29859.95490157834, 30141.849290753624, 2664.3072801071667, 2688.448867982059, 2695.854261938668, 2788.8605569721635, 2869.827810331366, 484189.0, 488708.1129188734, 489621.0, 490640.0, 490925.0, 34054.82972585121, 59213.70393148093, 59409.702951484964, 59982.0, 60199.69900150471, 31876.68123318737, 33083.00750977475, 35108.82445587807, 35340.11649708778, 36076.917692469266, 14381.352839122264, 14624.780628290584, 14793.926030369994, 14819.555413337617, 14835.10989340643, 12554.497820087277, 12648.494060237672, 12652.557160499338, 12664.923481504175, 12669.40277243163, 7814.6874125034365, 7827.099883513355, 7830.138684744674, 7864.370850331959, 7904.881426778603, 3291.2759192977505, 3305.93388132236, 3306.5866766654167, 3314.13832403572, 3356.983215083909, 841.6128580852754, 961.9759506012266, 999.0409207161061, 1008.5209525475341, 1056.478670714169, 27493.86253068722, 29271.268218294714, 29796.851015745215, 29913.551296730566, 29961.550576741367, 954.355809828363, 1074.7044562745307, 1079.5250089960393, 1094.7700982793542, 1187.7743228786676, 14053.297335133408, 14657.853421465863, 14846.18345990981, 15152.621184470476, 15244.0, 705252.0, 711163.0, 711946.0, 713246.0, 713868.0, 56977.71511142418, 58813.94151204687, 59008.819823603284, 59118.40881591215, 59195.40804591985, 1309.1228876652665, 1350.1224204267305, 1352.594221733476, 1437.7699568069058, 1457.271364317841, 29286.853565732035, 30041.849790750908, 30318.393632127674, 30408.42769980703, 30713.385732285675, 48866.5113348862, 49801.501984981136, 49805.0, 50349.74825125851, 50484.99030019453, 532156.0, 532275.0, 532391.0, 532787.0, 534328.0, 22708.432289192904, 24983.0, 25581.744182557937, 25811.096611619254, 25873.741262587133, 45680.31479527876, 45790.0, 46135.76932115318, 46775.53224467712, 47607.52392476169, 32819.50770738489, 36329.63670363263, 37629.62370376261, 38334.84995450029, 38356.0, 36013.0, 36254.637453625124, 36312.63687363092, 36795.08012299607, 37261.5095396192, 36967.63032369749, 37852.05369865776, 38016.0, 38698.80650596729, 39216.0, 28231.57652635253, 30760.384792304478, 31153.37693246077, 31755.364892702477, 33017.0, 22321.44196395103, 24408.145714899892, 24566.50866982686, 25009.0, 25217.621735674347], "value": 18261.58184678293, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.80406188781836, 379.3342683590299, 384.6730279262627, 386.3644305118079, 394.61919247925766, 19516.804831951675, 20260.0, 21438.678419823715, 21438.89280553597, 21482.248121315737, 20397.694034589455, 20530.973451327412, 21309.0, 21380.893095534502, 21405.0, 4810.567048965592, 5291.391489978644, 6020.096985452174, 6049.788257410992, 6063.848403789896, 5815.796447124351, 5862.765489380419, 5961.612495187799, 6011.5491338149595, 6014.0978853172155, 5747.770089196448, 5780.0751879699255, 5833.270841144857, 5867.589268751189, 6010.729517171738, 4644.326572646981, 5656.038473459481, 5942.821715348525, 5963.2247807784925, 5980.820575382745, 5040.546350828443, 5178.792848286064, 5217.426083130853, 5219.19102539107, 5223.190405487156, 13310.733785324239, 13368.732625347537, 24555.877220613962, 25027.87374568149, 25959.35101622456, 1964.165229777339, 2088.289981406329, 2207.834412419059, 2321.3500219938446, 2736.7673747731465, 9487.430754154773, 9830.803383932353, 10101.545430455648, 10259.43573103479, 13054.477820887154, 1682.1589205397302, 1699.9065051422167, 1710.2902295547333, 1766.8409843114052, 1833.4316361927845, 115966.0, 116478.41760791227, 118891.0, 120600.39699801532, 120708.0, 11214.719632009102, 11475.598354057567, 11571.537138514364, 11696.70758231051, 11894.82157767634, 14760.114393136446, 14834.480793172403, 14866.108033518023, 14890.181040042902, 14913.478028268959, 8352.66589336432, 8715.346349023848, 8722.91277087221, 8723.127687231226, 8828.249598784047, 8690.435121717195, 8699.391042626956, 8772.605232764541, 8799.428037177562, 8802.735917922473, 4547.772611369418, 4583.747893865802, 4586.77066146698, 4587.564181402761, 4605.815767369335, 1851.7223116049881, 1894.952626184329, 1934.3654611852962, 1940.1657287366286, 1961.1763059514753, 869.4696235296464, 905.1444538695648, 954.19370631816, 955.5317894231777, 981.1611072532882, 19737.703934440993, 19885.502862428264, 20667.966601669857, 20711.58576828455, 21105.89447052755, 833.6415341403134, 897.1297841094129, 904.2494729374604, 942.4251206763869, 947.9383840050513, 10217.13154381871, 10655.733606659896, 10887.292325998786, 10900.07349375312, 11045.061169800496, 603956.0, 604300.0, 606391.0, 621280.0, 621441.8927905422, 100130.0, 124614.0, 129414.0, 134534.74770298152, 137259.31370343285, 952.5237381309346, 967.666155176463, 984.4634674102631, 990.3711143423977, 992.3081146644953, 15950.0, 17093.145342732565, 17791.644167116843, 18506.0, 18514.259429623202, 50438.0, 52098.218526722885, 53783.73108134434, 54567.45432545624, 54621.0, 70747.64626176836, 71308.0, 71801.0, 72095.0, 72772.63613681898, 7835.413435983828, 8042.477238979448, 8209.05595856484, 11415.486303116213, 12273.140880138662, 12609.180403273758, 12657.556985505464, 12969.221846689228, 13189.604311870658, 13470.865291346961, 11142.72143196394, 11297.943510282725, 11694.649160525198, 11803.583569971659, 11889.048876090064, 11074.0, 11286.266392684449, 11349.205555611028, 11653.76692466129, 11679.591214307458, 11181.888181118085, 11516.251443656136, 11899.762004759683, 11930.642080737593, 12025.338606376383, 12274.324912129918, 12282.385880706108, 12344.81482777741, 12577.308248046445, 12623.621291361274, 11651.359175245443, 11669.649910502698, 11855.58505452305, 11892.048636108917, 11975.700607484534], "value": 9662.511465639986, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.441423430627, 1571.4971209213054, 1601.3514526616718, 1810.8098649641788, 1945.6011517638885, 7677.347425468834, 8680.869786953193, 8732.95633521833, 8869.60086796094, 8971.596278167483, 8567.700130495434, 8819.029906710257, 8822.647094116228, 8825.382223244376, 8835.425697329669, 2104.3581707579183, 2316.3051084674617, 2346.77705617966, 2365.1012615206196, 2385.8329916905823, 2377.738448770634, 2401.7958473529684, 2407.3981504623844, 2408.6266628672583, 2415.915442959497, 2332.9650055249185, 2367.4081479630095, 2373.193114341124, 2381.20229723043, 2383.249276477904, 2058.7632422271404, 2278.5670722562827, 2322.3381336319153, 2359.622460406327, 2359.681443005207, 2335.7430682625068, 2346.214018303853, 2369.2300002499082, 2373.6202207646866, 2376.572217000939, 8707.129287071306, 8774.341924355635, 8788.64845406183, 8790.164934331227, 8798.780030499225, 5257.290265814143, 5294.788208471695, 5352.2239275305255, 5354.303940487751, 5375.838724838261, 3216.4210442120407, 3308.8841890533718, 3389.4237979543536, 3399.1502124468884, 3404.3701915145775, 945.5933948402185, 971.1502435369052, 975.2149519636689, 980.5832521178542, 989.4113002763344, 11567.305961642329, 11596.652100437, 11613.477393517313, 11616.767664646659, 11629.94185029078, 4662.860114196579, 4731.4558825735485, 4796.9760151199025, 4818.373611430492, 4827.637927155477, 9924.751881202883, 9955.950220248853, 9957.651482198089, 9959.452230127412, 9980.800383992424, 5085.74571271435, 5106.080905437057, 5152.742362881841, 5179.04187725272, 5193.3767947845745, 4430.955690443118, 4589.242774942164, 4800.423949126058, 4938.8765280868265, 4940.209566469348, 3868.593797651206, 3944.9091981249985, 3983.92032159361, 4015.77913214776, 4045.2314060328445, 1263.8357013588175, 1271.3007845684836, 1272.7136394311206, 1277.1251692590606, 1280.0, 825.7316372179066, 829.9128591497927, 876.1063715010654, 901.8016036471965, 910.7222297199418, 5715.942840571541, 5716.971415142981, 5750.079987202028, 5856.4143585641095, 5888.32182827892, 425.3874420833989, 435.5492065711973, 440.25816499198953, 443.40362212823703, 444.5265791931582, 3274.459714147139, 3324.25204329024, 3492.511048453219, 3927.19492504036, 3979.7413168143976, 10367.63713270032, 10655.946720266504, 10771.676849694522, 10897.891021089847, 10909.34543927367, 10849.349039057683, 10858.565657373774, 10870.293430926964, 10876.293040952312, 10890.782184356269, 7726.227377262228, 8147.3482121431325, 8172.387070969582, 8176.918230817735, 8215.876761848576, 5714.285714285715, 5868.501177399969, 5942.019566771434, 5991.0, 6020.397960203944, 8089.271965523131, 10011.249156313452, 10065.295429319878, 10066.295359324777, 10109.0, 9652.613895444385, 9839.212862970802, 10221.437820919624, 10407.531661075118, 10411.219158562986, 6700.195976482853, 6930.06444130036, 7109.608971506616, 7165.928340716526, 7435.219301973215, 8288.21261980111, 8327.750167494985, 8410.495370277802, 8479.830403391774, 8492.150784921458, 7177.641117943977, 7324.670389832364, 7441.962790186231, 7599.810004749705, 7605.315521603082, 7169.5698258104685, 7275.163356214103, 7292.708291668274, 7364.815879602838, 7425.925740742523, 6804.4216241620015, 6837.965810170917, 6862.553933994274, 6894.0, 6894.8621027580175, 4749.810007599795, 4781.0, 4829.517048295143, 4902.534259245366, 5015.072211640858, 4417.293233082692, 4447.688661793773, 4491.977540112279, 4499.280115181556, 4525.547445255448], "value": 4431.869725919398, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.2926352335535, 307.04355931274085, 309.5480598326444, 313.7787859559011, 317.5189587774523, 4616.1460129875995, 4673.270889770785, 5199.6360254782185, 5261.210818377245, 5263.842084737459, 4397.05463325385, 5025.698458092508, 5174.2238664200295, 5216.15719432909, 5230.267762513242, 3443.7589368744198, 3523.3481805865867, 4000.9399859002137, 4036.3945408188715, 4125.587441255881, 3403.4724617684296, 4008.474660963589, 4016.5782592827773, 4021.1153546219784, 4022.597740225983, 3552.467129930505, 3984.8804535863824, 3995.9400608990886, 4007.2185923744883, 4023.7585744855255, 3760.8529398533437, 3882.7087968402484, 3922.8430862765454, 3941.054147004727, 4031.314676505001, 2984.567237750514, 3231.7737758356925, 3485.9651403485896, 3503.929921401583, 3530.329237444903, 4316.417283666696, 4978.00439912019, 5027.698338099689, 5053.191489361721, 5078.4413714491375, 4058.715889887709, 4201.075763332093, 4522.9547704522865, 4601.746903920283, 4608.28571571407, 4693.389859318267, 4715.269133284346, 4804.279358096279, 5488.286522752017, 5504.614676972614, 3267.673232676737, 3791.7487229214294, 3799.2211596622624, 3817.2747178036084, 3829.5404551453726, 4881.926771098365, 4914.0, 5473.917891231635, 5902.704864756745, 5995.37048609894, 3193.8083714977174, 3375.9493607595905, 3685.649863263039, 3795.981020094937, 3808.638179372955, 3587.2287458196615, 3866.2847373235477, 3876.476675648751, 3898.9805050975133, 3936.1340505088833, 4205.411242426063, 4522.525134860858, 4531.0, 4549.272116461417, 4553.977230113895, 5093.694378337312, 5407.45925407464, 5493.533049690741, 5568.749406276727, 5634.802781902614, 1062.5484169228203, 1172.6104566089211, 1236.6351926181799, 1296.7665820152463, 1319.0436933223527, 653.9210303000087, 768.4812751392899, 796.3907610677836, 810.2181394953898, 818.4884447220487, 4905.901881962341, 4924.285978533093, 5106.4382917879, 5115.335006449212, 5148.9742551287, 4888.0, 5044.571211446994, 5359.678419294854, 5550.666959982414, 5708.229389032511, 4885.389326334208, 5141.38303403594, 5145.665531740425, 5150.768215430239, 5173.818916337986, 2956.600858884066, 2968.302448924544, 2981.3292009297734, 2997.6103106596, 3006.128222815359, 5108.897822043538, 5220.712860792617, 5315.813946511854, 5968.552358573038, 6010.248718910137, 4864.270359446112, 5422.376426710979, 5692.459216374438, 5727.684977326204, 5832.504237139806, 5163.948360516347, 5761.423857614289, 5842.415758424124, 5884.9705751470965, 5917.05327147663, 5077.365329333833, 5210.837502436598, 5660.490555849993, 5670.319561652628, 5670.603057785916, 4931.852044438672, 5570.5265052470995, 5579.7489112990825, 5614.9719251403485, 5636.1827535008015, 5250.868728281824, 5330.466953304794, 5496.450354964471, 5782.8434313136695, 5913.763449461973, 3570.785752854837, 3641.617630148902, 3763.3844478087467, 3785.0, 3804.410316400962, 4345.783180709406, 4437.467503899424, 5417.133258678593, 5557.72211389437, 5661.999140455565, 4899.525739501529, 5440.6735595864375, 5451.7274136292235, 5473.8357849264585, 5506.439505629741, 4541.795619197078, 4698.718076915396, 5447.400785913467, 5508.834734957958, 5512.834614961557, 4913.361263035782, 5194.844154675366, 5426.484483974016, 5482.424345443671, 5511.905475919285, 4802.975985120052, 5184.507471790174, 5418.647787893774, 5454.181872718965, 5464.3442786865835, 4650.9767451162525, 4827.541383568555, 5396.463662078817, 5418.8620389717025, 5422.3732880136595], "value": 3968.221341378072, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.8124243969248, 669.9765508207214, 670.3363669966732, 688.2841844481736, 696.8815301398762, 12550.623481295575, 13282.867171328284, 14519.128852268857, 15151.924240378796, 15184.54446366608, 13617.795733064011, 13973.720525589508, 13997.580072597813, 14054.0, 14108.0, 719.7984564321972, 744.9515781474214, 887.7780554861284, 890.5725251879102, 892.8169725206317, 700.4186525184085, 723.5043994863536, 862.1249431826701, 892.5537231384308, 894.5437826708393, 893.8033632600817, 899.6176624934409, 924.8797656304671, 931.580788645109, 934.9252059835197, 723.6417973103295, 919.7608621758324, 931.5481991234246, 931.683227702581, 934.4299977014016, 659.426299119764, 680.2653134614653, 680.7685386968454, 682.5665702279089, 684.6302996381947, 14911.552653420415, 15088.924555377263, 15099.0, 15151.318190681446, 15155.0, 11939.820902686466, 11962.461689224006, 12001.0, 12220.2667839929, 12355.691107722289, 9895.703128906145, 10839.512221949952, 10869.130469562417, 10954.780904381947, 11315.9257488846, 4007.238624661305, 4014.3139881249654, 4041.6362527372385, 4163.084121493297, 4266.765327906964, 83137.16862831355, 83810.58094709548, 83917.16082839154, 84546.15453845444, 85202.0, 9349.205317548085, 9377.531123443801, 9480.857787133198, 9582.616695332108, 9739.318247722733, 20138.295159669342, 20682.0, 20688.793112068986, 20700.275490358068, 20701.79298207029, 7374.483786134919, 7433.070866141732, 7445.404367650575, 7542.962285188649, 7564.9243507565325, 3835.7890316032326, 3922.114185493042, 3967.1272320089533, 3990.20195960809, 4034.1326614777963, 3328.184594774296, 3372.1232479555492, 3454.6890779829937, 3457.8789742358895, 3461.671141241578, 616.5098746496546, 618.6133666458463, 624.2664868779166, 625.7246811402969, 630.7666163519435, 511.8976204759063, 514.5137844736688, 533.2055237695805, 571.9742611582489, 572.8281515545319, 7610.467267291237, 7789.766307010798, 7884.724034658874, 8014.799630009296, 8143.015740850907, 474.5657723183249, 488.6082625547923, 498.048726931559, 498.6213120721209, 596.2844586496221, 3454.2573346730574, 3534.9646503534636, 3712.925741485209, 3729.4965179700935, 3769.095417099876, 70886.0, 89013.0, 89583.55208223917, 89661.0, 93041.5347923256, 78387.60806195933, 79909.80135297975, 80211.7968230477, 82420.0, 84703.0, 71414.99999999897, 72141.0, 72421.0, 72558.27441725515, 72699.27300726925, 7169.7849064528145, 7197.244289349753, 7220.963895180701, 7296.452766042462, 7323.194448610755, 45014.549854502344, 45292.0, 45352.0, 45811.0, 47711.52288477071, 53197.0, 56922.7153864228, 57306.0, 60577.788444229984, 62122.0, 8781.780455488664, 9329.486878221764, 9435.05649435051, 9473.573689183866, 10091.848622270525, 10338.500096970094, 10377.907410446549, 10549.947250264007, 10614.522346494272, 10700.143988481059, 9691.030896910535, 9747.463889486145, 9785.559649815634, 9866.851997219905, 9955.601775928882, 8300.501969881827, 9443.527823608929, 9991.450470224207, 10035.598576057168, 10132.74668133273, 9670.70987870365, 9756.902430975882, 9832.16426603718, 10067.345622534513, 10099.0, 8272.586370681562, 8323.334133269445, 8619.310455163697, 8668.479891206547, 8843.380963332762, 8279.898395218965, 8375.581220938806, 8706.259967902799, 8901.688440904538, 8904.109589041043], "value": 6531.489932194828, "unit": "score"}], "name": "dromaeo_css", "value": 6986.427415427475, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 112.0, 112.0, 112.0, 112.0, 113.0, 113.0, 115.0, 110.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 118.0, 118.0, 201.0, 116.0, 118.0, 138.0, 116.0, 155.0, 119.0], "value": 135.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 188.0, 183.0, 184.0, 185.0, 182.0, 183.0, 183.0, 186.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 61.0, 120.0, 57.0, 58.0, 66.0, 57.0, 97.0, 56.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 102.0, 199.0, 99.0, 89.0, 77.0, 105.0, 80.0, 78.0, 109.0], "value": 103.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 89.0, 91.0, 88.0, 91.0, 88.0, 91.0, 89.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 95.0, 107.0, 149.0, 96.0, 95.0, 96.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 85.0, 83.0, 85.0, 83.0, 84.0, 85.0, 95.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 57.0, 55.0, 57.0, 57.0, 56.0, 121.0, 58.0, 55.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 73.0, 76.0, 71.0, 72.0, 70.0, 70.0, 71.0, 70.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 85.0, 85.0, 84.0, 80.0, 84.0, 85.0, 87.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 145.0, 144.0, 150.0, 137.0, 137.0, 133.0, 136.0, 211.0, 140.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 162.0, 167.0, 166.0, 151.0, 203.0, 227.0, 167.0, 163.0, 167.0], "value": 173.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 66.0, 66.0, 66.0, 67.0, 67.0, 65.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1488.8999999999999, "alertThreshold": 2.0}]} 06:47:25 INFO - Return code: 0 06:47:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:47:26 ERROR - # TBPL SUCCESS # 06:47:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:47:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:47:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:47:26 INFO - 2016-05-24 06:47:26.029 screenresolution[1684:10394] starting screenresolution argv=screenresolution get 06:47:26 INFO - 2016-05-24 06:47:26.032 screenresolution[1684:10394] Display 0: 1600x1200x32@60 06:47:26 INFO - 2016-05-24 06:47:26.039 screenresolution[1685:10397] starting screenresolution argv=screenresolution list 06:47:26 INFO - Available Modes on Display 0 06:47:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:47:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:47:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:47:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:47:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:47:26 INFO - Intel Iris: 06:47:26 INFO - Chipset Model: Intel Iris 06:47:26 INFO - Type: GPU 06:47:26 INFO - Bus: Built-In 06:47:26 INFO - VRAM (Dynamic, Max): 1536 MB 06:47:26 INFO - Vendor: Intel (0x8086) 06:47:26 INFO - Device ID: 0x0a2e 06:47:26 INFO - Revision ID: 0x0009 06:47:26 INFO - Displays: 06:47:26 INFO - Display: 06:47:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:47:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:47:26 INFO - Display Serial Number: 1600x1200 60 06:47:26 INFO - Main Display: Yes 06:47:26 INFO - Mirror: Off 06:47:26 INFO - Online: Yes 06:47:26 INFO - Rotation: Supported 06:47:26 INFO - Return code: 0 06:47:26 INFO - Running post-action listener: _resource_record_post_action 06:47:26 INFO - ##### 06:47:26 INFO - ##### Finished run-tests step (success) 06:47:26 INFO - ##### 06:47:26 INFO - Running post-run listener: _resource_record_post_run 06:47:26 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32312832; Write bytes: 694575616; Read time: 322; Write time: 2074 06:47:26 INFO - TinderboxPrint: CPU usage
53.8% 06:47:26 INFO - TinderboxPrint: I/O read bytes / time
32,312,832 / 322 06:47:26 INFO - TinderboxPrint: I/O write bytes / time
694,575,616 / 2,074 06:47:26 INFO - TinderboxPrint: CPU idle
2,241.2 (46.2%) 06:47:26 INFO - TinderboxPrint: CPU system
972.5 (20.1%) 06:47:26 INFO - TinderboxPrint: CPU user
1,635.9 (33.7%) 06:47:26 INFO - TinderboxPrint: Swap in / out
424,730,624 / 0 06:47:26 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 383918592; Write bytes: 377382912; Read time: 29218; Write time: 703 06:47:26 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31391232; Write bytes: 295340544; Read time: 309; Write time: 1321 06:47:26 INFO - Running post-run listener: _upload_blobber_files 06:47:26 INFO - Blob upload gear active. 06:47:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:47:26 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 06:47:26 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'] 06:47:26 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 06:47:27 INFO - (blobuploader) - INFO - Open directory for files ... 06:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:47:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:47:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:47:27 INFO - (blobuploader) - INFO - Done attempting. 06:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:47:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:47:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:47:28 INFO - (blobuploader) - INFO - Done attempting. 06:47:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:47:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:47:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:47:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:47:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:47:32 INFO - (blobuploader) - INFO - Done attempting. 06:47:32 INFO - (blobuploader) - INFO - Iteration through files over. 06:47:32 INFO - Return code: 0 06:47:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:47:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:47:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6cabc005699e87ad01fe1dc49366557253490defccf676c72de44cb8e8de2403b402ba044041da1cecc1be71f9542ae5f48733efde2480ae39581d46042642f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffb5b26a81e9e7417c63c9f5d928419667a05a155fd9ba3e08d3870f74fffc467b20e3d996dbeec02c4fa3105d94df2a21dbdcec7c6b2183fdd9e2f3615beeb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c403f44a2ff992edba71e78648e333fcf789c5b26011eef12278dcee0affb14f82524b9ab13941c255dd12995d5e219b192a6ab4f745aee57877a73b2042c49"} 06:47:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:47:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:47:32 INFO - Contents: 06:47:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6cabc005699e87ad01fe1dc49366557253490defccf676c72de44cb8e8de2403b402ba044041da1cecc1be71f9542ae5f48733efde2480ae39581d46042642f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffb5b26a81e9e7417c63c9f5d928419667a05a155fd9ba3e08d3870f74fffc467b20e3d996dbeec02c4fa3105d94df2a21dbdcec7c6b2183fdd9e2f3615beeb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c403f44a2ff992edba71e78648e333fcf789c5b26011eef12278dcee0affb14f82524b9ab13941c255dd12995d5e219b192a6ab4f745aee57877a73b2042c49"} 06:47:32 INFO - Running post-run listener: copy_logs_to_upload_dir 06:47:32 INFO - Copying logs to upload dir... 06:47:32 INFO - mkdir: /builds/slave/test/build/upload/logs 06:47:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.391848 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-24 06:47:32.250927) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 06:47:32.254579) ========= 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.Kco8RkJduc/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.xGAHrDJmAz/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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/a6cabc005699e87ad01fe1dc49366557253490defccf676c72de44cb8e8de2403b402ba044041da1cecc1be71f9542ae5f48733efde2480ae39581d46042642f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffb5b26a81e9e7417c63c9f5d928419667a05a155fd9ba3e08d3870f74fffc467b20e3d996dbeec02c4fa3105d94df2a21dbdcec7c6b2183fdd9e2f3615beeb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c403f44a2ff992edba71e78648e333fcf789c5b26011eef12278dcee0affb14f82524b9ab13941c255dd12995d5e219b192a6ab4f745aee57877a73b2042c49"} build_url:https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010897 build_url: 'https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/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/a6cabc005699e87ad01fe1dc49366557253490defccf676c72de44cb8e8de2403b402ba044041da1cecc1be71f9542ae5f48733efde2480ae39581d46042642f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ffb5b26a81e9e7417c63c9f5d928419667a05a155fd9ba3e08d3870f74fffc467b20e3d996dbeec02c4fa3105d94df2a21dbdcec7c6b2183fdd9e2f3615beeb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4c403f44a2ff992edba71e78648e333fcf789c5b26011eef12278dcee0affb14f82524b9ab13941c255dd12995d5e219b192a6ab4f745aee57877a73b2042c49"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 06:47:32.284193) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:47:32.284499) ========= 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.Kco8RkJduc/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.xGAHrDJmAz/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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.005163 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 06:47:32.333420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 06:47:32.333730) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 06:47:32.334072) ========= ========= Total master_lag: 0.17 =========