builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0266 starttime: 1464310400.41 results: success (0) buildid: 20160526165248 builduid: 66d4e50227fe46838a3f17e87152866d revision: 34ac60e5b29c55eba69ab7e769dbc3b2eb7d5321 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.418606) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.419025) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.419294) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.446909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.447185) ========= 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.0CLLkwBv9H/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.bXggMFIWab/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-26 17:53:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25 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% 141M=0s 2016-05-26 17:53:20 (141 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140571 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.628181) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.628474) ========= 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.0CLLkwBv9H/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.bXggMFIWab/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.024820 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.706194) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:20.707023) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 34ac60e5b29c55eba69ab7e769dbc3b2eb7d5321 --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 34ac60e5b29c55eba69ab7e769dbc3b2eb7d5321 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0CLLkwBv9H/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.bXggMFIWab/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-26 17:53:20,741 truncating revision to first 12 chars 2016-05-26 17:53:20,742 Setting DEBUG logging. 2016-05-26 17:53:20,742 attempt 1/10 2016-05-26 17:53:20,742 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/34ac60e5b29c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 17:53:21,038 unpacking tar archive at: fx-team-34ac60e5b29c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.565890 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:21.302078) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:21.302383) ========= script_repo_revision: 34ac60e5b29c55eba69ab7e769dbc3b2eb7d5321 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:21.302867) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:21.303144) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 17:53:21.525846) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-26 17:53:21.526104) ========= /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.0CLLkwBv9H/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.bXggMFIWab/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:53:21 INFO - MultiFileLogger online at 20160526 17:53:21 in /builds/slave/test 17:53:21 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 17:53:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:53:21 INFO - {'append_to_log': False, 17:53:21 INFO - 'base_work_dir': '/builds/slave/test', 17:53:21 INFO - 'blob_upload_branch': 'Fx-Team', 17:53:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:53:21 INFO - 'branch': 'Fx-Team', 17:53:21 INFO - 'buildbot_json_path': 'buildprops.json', 17:53:21 INFO - 'config_files': ('talos/mac_config.py',), 17:53:21 INFO - 'default_actions': ('clobber', 17:53:21 INFO - 'read-buildbot-config', 17:53:21 INFO - 'download-and-extract', 17:53:21 INFO - 'populate-webroot', 17:53:21 INFO - 'create-virtualenv', 17:53:21 INFO - 'install', 17:53:21 INFO - 'run-tests'), 17:53:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:53:21 INFO - 'download_minidump_stackwalk': True, 17:53:21 INFO - 'download_symbols': 'ondemand', 17:53:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:53:21 INFO - 'tooltool.py': '/tools/tooltool.py', 17:53:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:53:21 INFO - '/tools/misc-python/virtualenv.py')}, 17:53:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:53:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:53:21 INFO - 'installer_path': 'installer.exe', 17:53:21 INFO - 'log_level': 'info', 17:53:21 INFO - 'log_name': 'talos', 17:53:21 INFO - 'log_to_console': True, 17:53:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:53:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:53:21 INFO - 'opt_config_files': (), 17:53:21 INFO - 'pip_index': False, 17:53:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:53:21 INFO - 'cmd': ('bash', 17:53:21 INFO - '-c', 17:53:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:53:21 INFO - 'enabled': True, 17:53:21 INFO - 'halt_on_failure': False, 17:53:21 INFO - 'name': 'check_screen_resolution'},), 17:53:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:53:21 INFO - 'cmd': ('bash', 17:53:21 INFO - '-c', 17:53:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:53:21 INFO - 'enabled': True, 17:53:21 INFO - 'halt_on_failure': False, 17:53:21 INFO - 'name': 'check_screen_resolution'},), 17:53:21 INFO - 'run_cmd_checks_enabled': True, 17:53:21 INFO - 'sps_profile': False, 17:53:21 INFO - 'sps_profile_interval': 0, 17:53:21 INFO - 'suite': 'dromaeojs', 17:53:21 INFO - 'system_bits': '32', 17:53:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:53:21 INFO - 'title': 't-yosemite-r7-0266', 17:53:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:53:21 INFO - 'use_talos_json': True, 17:53:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:53:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:53:21 INFO - 'work_dir': 'build'} 17:53:21 INFO - ##### 17:53:21 INFO - ##### Running clobber step. 17:53:21 INFO - ##### 17:53:21 INFO - Running pre-action listener: _resource_record_pre_action 17:53:21 INFO - Running main action method: clobber 17:53:21 INFO - rmtree: /builds/slave/test/build 17:53:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:53:23 INFO - Running post-action listener: _resource_record_post_action 17:53:23 INFO - ##### 17:53:23 INFO - ##### Finished clobber step (success) 17:53:23 INFO - ##### 17:53:23 INFO - ##### 17:53:23 INFO - ##### Running read-buildbot-config step. 17:53:23 INFO - ##### 17:53:23 INFO - Running pre-action listener: _resource_record_pre_action 17:53:23 INFO - Running main action method: read_buildbot_config 17:53:23 INFO - Using buildbot properties: 17:53:23 INFO - { 17:53:23 INFO - "project": "", 17:53:23 INFO - "product": "firefox", 17:53:23 INFO - "script_repo_revision": "production", 17:53:23 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 17:53:23 INFO - "repository": "", 17:53:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 17:53:23 INFO - "buildid": "20160526165248", 17:53:23 INFO - "pgo_build": "False", 17:53:23 INFO - "basedir": "/builds/slave/test", 17:53:23 INFO - "buildnumber": 26, 17:53:23 INFO - "slavename": "t-yosemite-r7-0266", 17:53:23 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:53:23 INFO - "platform": "yosemite_r7", 17:53:23 INFO - "scheduler": "tests-fx-team-macosx64-talos", 17:53:23 INFO - "branch": "fx-team", 17:53:23 INFO - "repo_path": "integration/fx-team", 17:53:23 INFO - "revision": "34ac60e5b29c55eba69ab7e769dbc3b2eb7d5321", 17:53:23 INFO - "stage_platform": "macosx64", 17:53:23 INFO - "builduid": "66d4e50227fe46838a3f17e87152866d", 17:53:23 INFO - "slavebuilddir": "test" 17:53:23 INFO - } 17:53:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 17:53:23 INFO - Running post-action listener: _resource_record_post_action 17:53:23 INFO - ##### 17:53:23 INFO - ##### Finished read-buildbot-config step (success) 17:53:23 INFO - ##### 17:53:23 INFO - ##### 17:53:23 INFO - ##### Running download-and-extract step. 17:53:23 INFO - ##### 17:53:23 INFO - Running pre-action listener: _resource_record_pre_action 17:53:23 INFO - Running main action method: download_and_extract 17:53:23 INFO - mkdir: /builds/slave/test/build/tests 17:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:53:23 INFO - https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:53:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:53:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 17:53:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:28 INFO - Downloaded 1199 bytes. 17:53:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 17:53:28 INFO - Using the following test package requirements: 17:53:28 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:53:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 17:53:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 17:53:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'jsshell-mac.zip'], 17:53:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 17:53:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 17:53:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 17:53:28 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 17:53:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 17:53:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 17:53:28 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 17:53:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:53:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:53:28 INFO - https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:53:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:53:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:53:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:31 INFO - Downloaded 18622021 bytes. 17:53:31 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'] 17:53:31 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 17:53:34 INFO - Return code: 0 17:53:34 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 17:53: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')]} 17:53:34 INFO - https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:53:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:53:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 17:53:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:40 INFO - Downloaded 18622021 bytes. 17:53:40 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'] 17:53:40 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 17:53:44 INFO - Return code: 0 17:53:44 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')]} 17:53:44 INFO - https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:53:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:53:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 17:53:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:46 INFO - Downloaded 10834034 bytes. 17:53:46 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'] 17:53:46 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 17:53:47 INFO - Return code: 0 17:53: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')]} 17:53:47 INFO - https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:53:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:53:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:53:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 17:53:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXTBD4snSNKct40W8AI4lQ/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 17:53:50 INFO - Downloaded 110432329 bytes. 17:53:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:53:50 INFO - mkdir: /builds/slave/test/properties 17:53:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:53:50 INFO - Writing to file /builds/slave/test/properties/build_url 17:53:50 INFO - Contents: 17:53:50 INFO - build_url:https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 17:53:51 INFO - Running post-action listener: _resource_record_post_action 17:53:51 INFO - Running post-action listener: set_extra_try_arguments 17:53:51 INFO - ##### 17:53:51 INFO - ##### Finished download-and-extract step (success) 17:53:51 INFO - ##### 17:53:51 INFO - ##### 17:53:51 INFO - ##### Running populate-webroot step. 17:53:51 INFO - ##### 17:53:51 INFO - Running pre-action listener: _resource_record_pre_action 17:53:51 INFO - Running main action method: populate_webroot 17:53:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:53:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:53:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:53:51 INFO - '--tppagecycles', 17:53:51 INFO - '7'], 17:53:51 INFO - 'tests': ['tsvgm']}}, 17:53:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:53:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:53:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:53:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:53:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:53:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:53:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['damp', 'tps']}, 17:53:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'tests': ['damp', 'tps']}, 17:53:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['dromaeo_dom']}, 17:53:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:53:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['a11yr', 17:53:51 INFO - 'ts_paint', 17:53:51 INFO - 'tpaint', 17:53:51 INFO - 'sessionrestore', 17:53:51 INFO - 'sessionrestore_no_auto_restore', 17:53:51 INFO - 'tabpaint']}, 17:53:51 INFO - 'other-e10s': {'tests': ['a11yr', 17:53:51 INFO - 'ts_paint', 17:53:51 INFO - 'tpaint', 17:53:51 INFO - 'sessionrestore', 17:53:51 INFO - 'sessionrestore_no_auto_restore', 17:53:51 INFO - 'tabpaint']}, 17:53:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:53:51 INFO - 'ts_paint', 17:53:51 INFO - 'tpaint', 17:53:51 INFO - 'sessionrestore', 17:53:51 INFO - 'sessionrestore_no_auto_restore', 17:53:51 INFO - 'tabpaint']}, 17:53:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:53:51 INFO - 'ts_paint', 17:53:51 INFO - 'tpaint', 17:53:51 INFO - 'sessionrestore', 17:53:51 INFO - 'sessionrestore_no_auto_restore', 17:53:51 INFO - 'tabpaint']}, 17:53:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['a11yr', 17:53:51 INFO - 'ts_paint', 17:53:51 INFO - 'tpaint', 17:53:51 INFO - 'sessionrestore', 17:53:51 INFO - 'sessionrestore_no_auto_restore', 17:53:51 INFO - 'tabpaint']}, 17:53:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['a11yr', 17:53:51 INFO - 'ts_paint', 17:53:51 INFO - 'tpaint', 17:53:51 INFO - 'sessionrestore', 17:53:51 INFO - 'sessionrestore_no_auto_restore', 17:53:51 INFO - 'tabpaint']}, 17:53:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['tsvgx', 17:53:51 INFO - 'tsvgr_opacity', 17:53:51 INFO - 'tart', 17:53:51 INFO - 'tscrollx', 17:53:51 INFO - 'cart']}, 17:53:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:53:51 INFO - 'tsvgr_opacity', 17:53:51 INFO - 'tart', 17:53:51 INFO - 'tscrollx', 17:53:51 INFO - 'cart']}, 17:53:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'talos_options': ['--disable-e10s'], 17:53:51 INFO - 'tests': ['tp5o']}, 17:53:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'tests': ['tp5o']}, 17:53:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'talos_options': ['--disable-e10s', 17:53:51 INFO - '--xperf_path', 17:53:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:53:51 INFO - 'tests': ['tp5n']}, 17:53:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:53:51 INFO - 'talos_options': ['--xperf_path', 17:53:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:53:51 INFO - 'tests': ['tp5n']}}, 17:53:51 INFO - 'talos.zip': {'path': '', 17:53:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:53:51 INFO - Running post-action listener: _resource_record_post_action 17:53:51 INFO - ##### 17:53:51 INFO - ##### Finished populate-webroot step (success) 17:53:51 INFO - ##### 17:53:51 INFO - ##### 17:53:51 INFO - ##### Running create-virtualenv step. 17:53:51 INFO - ##### 17:53:51 INFO - Running pre-action listener: _resource_record_pre_action 17:53:51 INFO - Running main action method: create_virtualenv 17:53:51 INFO - Creating virtualenv /builds/slave/test/build/venv 17:53:51 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 17:53:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:53:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:53:51 INFO - Using real prefix '/tools/python27' 17:53:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:53:52 INFO - Installing distribute.............................................................................................................................................................................................done. 17:53:55 INFO - Installing pip.................done. 17:53:55 INFO - Return code: 0 17:53:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:55 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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:53:55 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 17:53:55 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 17:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:55 INFO - 'HOME': '/Users/cltbld', 17:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:55 INFO - 'LOGNAME': 'cltbld', 17:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:55 INFO - 'MOZ_NO_REMOTE': '1', 17:53:55 INFO - 'NO_EM_RESTART': '1', 17:53:55 INFO - 'PAGER': '/bin/cat', 17:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:55 INFO - 'PWD': '/builds/slave/test', 17:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:55 INFO - 'SHELL': '/bin/bash', 17:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:53:55 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:55 INFO - 'USER': 'cltbld', 17:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:55 INFO - 'XPC_FLAGS': '0x0', 17:53:55 INFO - 'XPC_SERVICE_NAME': '0', 17:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:55 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 17:53:55 INFO - Cleaning up... 17:53:55 INFO - Return code: 0 17:53:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:53:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:53:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:53:55 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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:53:55 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 17:53:55 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 17:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:53:55 INFO - 'HOME': '/Users/cltbld', 17:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:53:55 INFO - 'LOGNAME': 'cltbld', 17:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:53:55 INFO - 'MOZ_NO_REMOTE': '1', 17:53:55 INFO - 'NO_EM_RESTART': '1', 17:53:55 INFO - 'PAGER': '/bin/cat', 17:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:53:55 INFO - 'PWD': '/builds/slave/test', 17:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:53:55 INFO - 'SHELL': '/bin/bash', 17:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:53:55 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:53:55 INFO - 'USER': 'cltbld', 17:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:53:55 INFO - 'XPC_FLAGS': '0x0', 17:53:55 INFO - 'XPC_SERVICE_NAME': '0', 17:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:53:55 INFO - Downloading/unpacking psutil>=3.1.1 17:53:55 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 17:53:55 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 17:53:55 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 17:53:55 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 17:53:55 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 17:53:55 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 17:53:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:53:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:53:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:53:59 INFO - Installing collected packages: psutil 17:53:59 INFO - Running setup.py install for psutil 17:53:59 INFO - building 'psutil._psutil_osx' extension 17:53:59 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 17:53:59 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 17:53:59 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 17:53:59 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 17:53:59 INFO - building 'psutil._psutil_posix' extension 17:53:59 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 17:53:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:53:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:53:59 INFO - ^ 17:53:59 INFO - 1 warning generated. 17:53:59 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 17:54:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:54:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:54:00 INFO - Successfully installed psutil 17:54:00 INFO - Cleaning up... 17:54:00 INFO - Return code: 0 17:54:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:00 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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:54:00 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 17:54:00 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 17:54:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:54:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:00 INFO - 'HOME': '/Users/cltbld', 17:54:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:00 INFO - 'LOGNAME': 'cltbld', 17:54:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:00 INFO - 'MOZ_NO_REMOTE': '1', 17:54:00 INFO - 'NO_EM_RESTART': '1', 17:54:00 INFO - 'PAGER': '/bin/cat', 17:54:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:00 INFO - 'PWD': '/builds/slave/test', 17:54:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:00 INFO - 'SHELL': '/bin/bash', 17:54:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:54:00 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:54:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:00 INFO - 'USER': 'cltbld', 17:54:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:00 INFO - 'XPC_FLAGS': '0x0', 17:54:00 INFO - 'XPC_SERVICE_NAME': '0', 17:54:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:54:00 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 17:54:00 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 17:54:00 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 17:54:00 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 17:54:00 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 17:54:00 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 17:54:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:54:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:54:02 INFO - Installing collected packages: mozsystemmonitor 17:54:02 INFO - Running setup.py install for mozsystemmonitor 17:54:03 INFO - Successfully installed mozsystemmonitor 17:54:03 INFO - Cleaning up... 17:54:03 INFO - Return code: 0 17:54:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:54:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:54:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:54:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:03 INFO - 'HOME': '/Users/cltbld', 17:54:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:03 INFO - 'LOGNAME': 'cltbld', 17:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:03 INFO - 'MOZ_NO_REMOTE': '1', 17:54:03 INFO - 'NO_EM_RESTART': '1', 17:54:03 INFO - 'PAGER': '/bin/cat', 17:54:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:03 INFO - 'PWD': '/builds/slave/test', 17:54:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:03 INFO - 'SHELL': '/bin/bash', 17:54:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:54:03 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:54:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:03 INFO - 'USER': 'cltbld', 17:54:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:03 INFO - 'XPC_FLAGS': '0x0', 17:54:03 INFO - 'XPC_SERVICE_NAME': '0', 17:54:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:03 INFO - Downloading/unpacking blobuploader==1.2.4 17:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:05 INFO - Downloading blobuploader-1.2.4.tar.gz 17:54:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:54:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:54:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:54:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:54:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:54:07 INFO - Downloading docopt-0.6.1.tar.gz 17:54:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:54:07 INFO - Installing collected packages: blobuploader, requests, docopt 17:54:07 INFO - Running setup.py install for blobuploader 17:54:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:54:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:54:07 INFO - Running setup.py install for requests 17:54:07 INFO - Running setup.py install for docopt 17:54:07 INFO - Successfully installed blobuploader requests docopt 17:54:07 INFO - Cleaning up... 17:54:08 INFO - Return code: 0 17:54:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54: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')]} 17:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54: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')]} 17:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:08 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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:54:08 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 17:54:08 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 17:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:08 INFO - 'HOME': '/Users/cltbld', 17:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:08 INFO - 'LOGNAME': 'cltbld', 17:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:08 INFO - 'MOZ_NO_REMOTE': '1', 17:54:08 INFO - 'NO_EM_RESTART': '1', 17:54:08 INFO - 'PAGER': '/bin/cat', 17:54:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:08 INFO - 'PWD': '/builds/slave/test', 17:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:08 INFO - 'SHELL': '/bin/bash', 17:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:54:08 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:08 INFO - 'USER': 'cltbld', 17:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:08 INFO - 'XPC_FLAGS': '0x0', 17:54:08 INFO - 'XPC_SERVICE_NAME': '0', 17:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:54:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-uyhDD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:54:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-dNvWeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:54:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-hdUCkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:54:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-ptF0Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:54:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-xDOJMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:54:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-25ojDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:54:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:54:08 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-JFdww3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-1L8eU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-DgoP8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Qr08Cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-xP69CB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-tskDcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-r7Nw7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-REpCX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-qTm9MV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:54:09 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Xq0rMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:54:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:54:10 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-ypjqh5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:54:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:54:10 INFO - Running setup.py install for manifestparser 17:54:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:54:10 INFO - Running setup.py install for mozcrash 17:54:10 INFO - Running setup.py install for mozdebug 17:54:10 INFO - Running setup.py install for mozdevice 17:54:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:54:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:54:10 INFO - Running setup.py install for mozfile 17:54:10 INFO - Running setup.py install for mozhttpd 17:54:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:54:10 INFO - Running setup.py install for mozinfo 17:54:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:54:11 INFO - Running setup.py install for mozInstall 17:54:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:54:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:54:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:54:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:54:11 INFO - Running setup.py install for mozleak 17:54:11 INFO - Running setup.py install for mozlog 17:54:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:54:11 INFO - Running setup.py install for moznetwork 17:54:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:54:11 INFO - Running setup.py install for mozprocess 17:54:11 INFO - Running setup.py install for mozprofile 17:54:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:54:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:54:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:54:12 INFO - Running setup.py install for mozrunner 17:54:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:54:12 INFO - Running setup.py install for mozscreenshot 17:54:12 INFO - Running setup.py install for moztest 17:54:12 INFO - Running setup.py install for mozversion 17:54:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:54:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:54:12 INFO - Cleaning up... 17:54:12 INFO - Return code: 0 17:54:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54:12 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')]} 17:54:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:12 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')]} 17:54:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:12 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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:54:12 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 17:54:12 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 17:54:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:54:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:12 INFO - 'HOME': '/Users/cltbld', 17:54:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:12 INFO - 'LOGNAME': 'cltbld', 17:54:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:12 INFO - 'MOZ_NO_REMOTE': '1', 17:54:12 INFO - 'NO_EM_RESTART': '1', 17:54:12 INFO - 'PAGER': '/bin/cat', 17:54:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:12 INFO - 'PWD': '/builds/slave/test', 17:54:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:12 INFO - 'SHELL': '/bin/bash', 17:54:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:54:12 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:54:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:12 INFO - 'USER': 'cltbld', 17:54:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:12 INFO - 'XPC_FLAGS': '0x0', 17:54:12 INFO - 'XPC_SERVICE_NAME': '0', 17:54:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:54:12 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-76GUNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-79hIqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-mDFkmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-hP8ULy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-p3gG6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-vAEH8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-dr2m9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-NX_iB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Vbpkm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:54:13 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)) 17:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:54:13 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-ncSDq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:54:14 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)) 17:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:54:14 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-dOeiXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:54:14 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)) 17:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:54:14 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-Xh2xpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:54:14 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)) 17:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:54:14 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-XFHfUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:54:14 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)) 17:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:54:14 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-NHCmsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:54:14 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)) 17:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:14 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-j8aXZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:54:14 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)) 17:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:54:14 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-865iPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:54:14 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)) 17:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:54:14 INFO - Running setup.py (path:/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/pip-VeRQMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:54:14 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)) 17:54:14 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)) 17:54:14 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)) 17:54:14 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)) 17:54:14 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)) 17:54:14 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)) 17:54:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:54:14 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 17:54:14 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 17:54:14 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 17:54:14 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 17:54:14 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 17:54:14 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 17:54:17 INFO - Downloading blessings-1.6.tar.gz 17:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:54:17 INFO - Installing collected packages: blessings 17:54:17 INFO - Running setup.py install for blessings 17:54:17 INFO - Successfully installed blessings 17:54:17 INFO - Cleaning up... 17:54:17 INFO - Return code: 0 17:54:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:54:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:54:17 INFO - Reading from file tmpfile_stdout 17:54:17 INFO - Current package versions: 17:54:17 INFO - blessings == 1.6 17:54:17 INFO - blobuploader == 1.2.4 17:54:17 INFO - docopt == 0.6.1 17:54:17 INFO - manifestparser == 1.1 17:54:17 INFO - mozInstall == 1.12 17:54:17 INFO - mozcrash == 0.17 17:54:17 INFO - mozdebug == 0.1 17:54:17 INFO - mozdevice == 0.48 17:54:17 INFO - mozfile == 1.2 17:54:17 INFO - mozhttpd == 0.7 17:54:17 INFO - mozinfo == 0.9 17:54:17 INFO - mozleak == 0.1 17:54:17 INFO - mozlog == 3.2 17:54:17 INFO - moznetwork == 0.27 17:54:17 INFO - mozprocess == 0.22 17:54:17 INFO - mozprofile == 0.28 17:54:17 INFO - mozrunner == 6.11 17:54:17 INFO - mozscreenshot == 0.1 17:54:17 INFO - mozsystemmonitor == 0.3 17:54:17 INFO - moztest == 0.7 17:54:17 INFO - mozversion == 1.4 17:54:17 INFO - psutil == 3.1.1 17:54:17 INFO - requests == 1.2.3 17:54:17 INFO - wsgiref == 0.1.2 17:54:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:17 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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:54:17 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 17:54:17 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 17:54:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:54:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:17 INFO - 'HOME': '/Users/cltbld', 17:54:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:17 INFO - 'LOGNAME': 'cltbld', 17:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:17 INFO - 'MOZ_NO_REMOTE': '1', 17:54:17 INFO - 'NO_EM_RESTART': '1', 17:54:17 INFO - 'PAGER': '/bin/cat', 17:54:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:17 INFO - 'PWD': '/builds/slave/test', 17:54:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:17 INFO - 'SHELL': '/bin/bash', 17:54:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:54:17 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:54:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:17 INFO - 'USER': 'cltbld', 17:54:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:17 INFO - 'XPC_FLAGS': '0x0', 17:54:17 INFO - 'XPC_SERVICE_NAME': '0', 17:54:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 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)) 17:54:18 INFO - Cleaning up... 17:54:18 INFO - Return code: 0 17:54:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:54:18 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')]} 17:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:18 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')]} 17:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:18 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 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:54:18 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 17:54:18 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 17:54:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:54:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:18 INFO - 'HOME': '/Users/cltbld', 17:54:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:18 INFO - 'LOGNAME': 'cltbld', 17:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:18 INFO - 'MOZ_NO_REMOTE': '1', 17:54:18 INFO - 'NO_EM_RESTART': '1', 17:54:18 INFO - 'PAGER': '/bin/cat', 17:54:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:18 INFO - 'PWD': '/builds/slave/test', 17:54:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:18 INFO - 'SHELL': '/bin/bash', 17:54:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:54:18 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:54:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:18 INFO - 'USER': 'cltbld', 17:54:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:18 INFO - 'XPC_FLAGS': '0x0', 17:54:18 INFO - 'XPC_SERVICE_NAME': '0', 17:54:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:18 INFO - Downloading/unpacking jsonschema 17:54:18 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 17:54:18 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 17:54:18 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 17:54:18 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 17:54:18 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 17:54:18 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 17:54:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:54:20 INFO - Downloading/unpacking functools32 (from jsonschema) 17:54:20 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 17:54:20 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 17:54:20 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 17:54:20 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 17:54:20 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 17:54:20 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 17:54:21 INFO - Downloading functools32-3.2.3-2.tar.gz 17:54:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:54:21 INFO - warning: no files found matching '*.txt' 17:54:21 INFO - no previously-included directories found matching 'build' 17:54:21 INFO - no previously-included directories found matching 'dist' 17:54:21 INFO - no previously-included directories found matching '.git*' 17:54:21 INFO - Installing collected packages: jsonschema, functools32 17:54:21 INFO - Running setup.py install for functools32 17:54:21 INFO - warning: no files found matching '*.txt' 17:54:21 INFO - no previously-included directories found matching 'build' 17:54:21 INFO - no previously-included directories found matching 'dist' 17:54:21 INFO - no previously-included directories found matching '.git*' 17:54:21 INFO - Successfully installed jsonschema functools32 17:54:21 INFO - Cleaning up... 17:54:21 INFO - Return code: 0 17:54:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:54:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:54:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:54:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10687aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106880570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6b34a7b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, '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.0CLLkwBv9H/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.bXggMFIWab/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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 17:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:54:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:54:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:54:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:54:21 INFO - 'HOME': '/Users/cltbld', 17:54:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:54:21 INFO - 'LOGNAME': 'cltbld', 17:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:54:21 INFO - 'MOZ_NO_REMOTE': '1', 17:54:21 INFO - 'NO_EM_RESTART': '1', 17:54:21 INFO - 'PAGER': '/bin/cat', 17:54:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:54:21 INFO - 'PWD': '/builds/slave/test', 17:54:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:54:21 INFO - 'SHELL': '/bin/bash', 17:54:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:54:21 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:54:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:54:21 INFO - 'USER': 'cltbld', 17:54:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:54:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:54:21 INFO - 'XPC_FLAGS': '0x0', 17:54:21 INFO - 'XPC_SERVICE_NAME': '0', 17:54:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:54:21 INFO - Downloading/unpacking flake8 17:54:21 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 17:54:21 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 17:54:21 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 17:54:21 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 17:54:21 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 17:54:21 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 17:54:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:54:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:54:24 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 17:54:24 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 17:54:24 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 17:54:24 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 17:54:24 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 17:54:24 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 17:54:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:54:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:54:24 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 17:54:24 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 17:54:24 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 17:54:24 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 17:54:24 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 17:54:24 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 17:54:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:54:25 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 17:54:25 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 17:54:25 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 17:54:25 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 17:54:25 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 17:54:25 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 17:54:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:54:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:54:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:54:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:54:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:54:25 INFO - no previously-included directories found matching 'docs/_build' 17:54:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:54:25 INFO - Running setup.py install for pep8 17:54:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:54:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:54:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:54:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:54:25 INFO - no previously-included directories found matching 'docs/_build' 17:54:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:54:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:54:25 INFO - Cleaning up... 17:54:25 INFO - Return code: 0 17:54:25 INFO - Running post-action listener: _resource_record_post_action 17:54:25 INFO - Running post-action listener: _start_resource_monitoring 17:54:25 INFO - Starting resource monitoring. 17:54:25 INFO - ##### 17:54:25 INFO - ##### Finished create-virtualenv step (success) 17:54:25 INFO - ##### 17:54:25 INFO - ##### 17:54:25 INFO - ##### Running install step. 17:54:25 INFO - ##### 17:54:25 INFO - Running pre-action listener: _resource_record_pre_action 17:54:25 INFO - Running main action method: install 17:54:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:54:26 INFO - Reading from file tmpfile_stdout 17:54:26 INFO - Detecting whether we're running mozinstall >=1.0... 17:54:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:54:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:54:26 INFO - Reading from file tmpfile_stdout 17:54:26 INFO - Output received: 17:54:26 INFO - Usage: mozinstall [options] installer 17:54:26 INFO - Options: 17:54:26 INFO - -h, --help show this help message and exit 17:54:26 INFO - -d DEST, --destination=DEST 17:54:26 INFO - Directory to install application into. [default: 17:54:26 INFO - "/builds/slave/test"] 17:54:26 INFO - --app=APP Application being installed. [default: firefox] 17:54:26 INFO - mkdir: /builds/slave/test/build/application 17:54:26 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'] 17:54:26 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 17:54:59 INFO - Reading from file tmpfile_stdout 17:54:59 INFO - Output received: 17:54:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:54:59 INFO - Running post-action listener: _resource_record_post_action 17:54:59 INFO - ##### 17:54:59 INFO - ##### Finished install step (success) 17:54:59 INFO - ##### 17:54:59 INFO - ##### 17:54:59 INFO - ##### Running run-tests step. 17:54:59 INFO - ##### 17:54:59 INFO - Running pre-action listener: _resource_record_pre_action 17:54:59 INFO - Running main action method: run_tests 17:54:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:54:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:54:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:54:59 INFO - 2016-05-26 17:54:59.881 screenresolution[1652:10511] starting screenresolution argv=screenresolution get 17:54:59 INFO - 2016-05-26 17:54:59.884 screenresolution[1652:10511] Display 0: 1600x1200x32@60 17:54:59 INFO - 2016-05-26 17:54:59.889 screenresolution[1653:10514] starting screenresolution argv=screenresolution list 17:54:59 INFO - Available Modes on Display 0 17:54:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:54:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:54:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:54:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:55:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:55:00 INFO - Intel Iris: 17:55:00 INFO - Chipset Model: Intel Iris 17:55:00 INFO - Type: GPU 17:55:00 INFO - Bus: Built-In 17:55:00 INFO - VRAM (Dynamic, Max): 1536 MB 17:55:00 INFO - Vendor: Intel (0x8086) 17:55:00 INFO - Device ID: 0x0a2e 17:55:00 INFO - Revision ID: 0x0009 17:55:00 INFO - Displays: 17:55:00 INFO - Display: 17:55:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:55:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:55:00 INFO - Display Serial Number: 1600x1200 60 17:55:00 INFO - Main Display: Yes 17:55:00 INFO - Mirror: Off 17:55:00 INFO - Online: Yes 17:55:00 INFO - Rotation: Supported 17:55:00 INFO - Return code: 0 17:55:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:55:00 INFO - Python 2.7.3 17:55:00 INFO - Return code: 0 17:55:00 INFO - grabbing minidump binary from tooltool 17:55:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:00 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 0x10671ed30>, '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 0x106542db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106715b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:55:00 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 17:55:00 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 17:55:00 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 17:55:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:55:00 INFO - Return code: 0 17:55:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:55:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:55:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:55:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:55:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:55:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:55:01 INFO - Return code: 0 17:55:01 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/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 17:55:01 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/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0266 --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 17:55:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0CLLkwBv9H/Render', 17:55:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:01 INFO - 'HOME': '/Users/cltbld', 17:55:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:01 INFO - 'LOGNAME': 'cltbld', 17:55:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:55:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:55:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:01 INFO - 'MOZ_NO_REMOTE': '1', 17:55:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:55:01 INFO - 'NO_EM_RESTART': '1', 17:55:01 INFO - 'PAGER': '/bin/cat', 17:55:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:01 INFO - 'PWD': '/builds/slave/test', 17:55:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:55:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:01 INFO - 'SHELL': '/bin/bash', 17:55:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bXggMFIWab/Listeners', 17:55:01 INFO - 'TMPDIR': '/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/', 17:55:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:01 INFO - 'USER': 'cltbld', 17:55:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:01 INFO - 'XPC_FLAGS': '0x0', 17:55:01 INFO - 'XPC_SERVICE_NAME': '0', 17:55:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:01 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/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0266', '--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 17:55:01 INFO - mozversion application_buildid: 20160526165248 17:55:01 INFO - mozversion application_changeset: 34ac60e5b29c55eba69ab7e769dbc3b2eb7d5321 17:55:01 INFO - mozversion application_display_name: Nightly 17:55:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:55:01 INFO - mozversion application_name: Firefox 17:55:01 INFO - mozversion application_remotingname: firefox 17:55:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:55:01 INFO - mozversion application_vendor: Mozilla 17:55:01 INFO - mozversion application_version: 49.0a1 17:55:01 INFO - mozversion platform_buildid: 20160526165248 17:55:01 INFO - mozversion platform_changeset: 34ac60e5b29c55eba69ab7e769dbc3b2eb7d5321 17:55:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:55:01 INFO - mozversion platform_version: 49.0a1 17:55:01 INFO - using testdate: 1464310501 17:55:01 INFO - actual date: 1464310501 17:55:01 INFO - starting webserver on 'localhost:49275' 17:55:01 INFO - SUITE-START | Running 2 tests 17:55:01 INFO - TEST-START | dromaeo_css 17:55:01 INFO - operating with platform_type : mac_ 17:55:01 INFO - Initialising browser for dromaeo_css test... 17:55:01 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpv1gCOG/profile http://localhost:49275/getInfo.html) 17:55:04 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 17:55:04 INFO - PROCESS | 1671 | colorDepth:24 17:55:04 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 17:55:04 INFO - PROCESS | 1671 | __metrics 17:55:04 INFO - PROCESS | 1671 | 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" 17:55:04 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:55:04 INFO - TEST-INFO | 1671: exit 0 17:55:04 INFO - Browser initialized. 17:55:04 INFO - Running cycle 1/1 for dromaeo_css test... 17:55:04 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpv1gCOG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:55:16 INFO - PROCESS | 1674 | RSS: Main: 241168384 17:55:16 INFO - PROCESS | 1674 | 17:56:08 INFO - PROCESS | 1674 | 1464310568087 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] 17:58:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 17:58:19 INFO - PROCESS | 1674 | RSS: Main: 342040576 17:58:19 INFO - PROCESS | 1674 | 18:01:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 18:01:22 INFO - PROCESS | 1674 | RSS: Main: 374931456 18:01:22 INFO - PROCESS | 1674 | 18:04:25 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 18:04:25 INFO - PROCESS | 1674 | RSS: Main: 372834304 18:04:25 INFO - PROCESS | 1674 | 18:07:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 18:07:28 INFO - PROCESS | 1674 | RSS: Main: 370855936 18:07:28 INFO - PROCESS | 1674 | 18:10:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 18:10:32 INFO - PROCESS | 1674 | RSS: Main: 342814720 18:10:32 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 18:13:35 INFO - PROCESS | 1674 | RSS: Main: 375947264 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | __start_tp_report 18:13:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 18:13:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 18:13:35 INFO - PROCESS | 1674 | |i|pagename|runs| 18:13:35 INFO - PROCESS | 1674 | |0;dojo.html;2995.700429957004;3432.262063656315;3447.7586568940183;3460.982695086524;3495.9825200874006;4922.532359425858;5545.2513910622;5639.66162030278;5639.774409023634;5673.716314184295;5679.57403194761;5703.74333155008;5793.942060579393;5797.275340582427;5830.9504289227925;5043.5208655177885;5072.923906141411;5132.97433512832;5139.563137133348;5221.138512145491;5189.532942035216;5227.738613069331;5241.528262456378;5244.816431424901;5271.103912334893;5083;5087.465816089294;5133.383993920754;5163.1480805667015;5171.405288391846;5137.357830271216;5141.15170996785;5158.871028224325;5163.845084647466;5223.947760522384;5127.5385215330425;5144.4083930348115;5172.715500647463;5182.818601348972;5240.973795131;4674.813007479696;5498.642588231752;5593.720313984284;5616.747246373923;5708.486236238717;6152.815415537496;6204.720787564571;6215.9378406216265;6225.066240063981;6238.1266622672865;6152.107944348045;6189.597676151036;6210.254769427653;6212.471939885115;6223.719932603044;5678.375378708339;5708.514776244066;5752.2234498342605;5786.623869448473;5855.912161317584;6214.5960512566535;6215.689215539205;6229;6239.06414037898;6240.594361366497;6101.654279549308;6132.6933653317155;6135.141080248769;6140.263168419819;6146.6004709693725;6101.725422356005;6119.106526814625;6124.877502449926;6171.47542458896;6190.473809726133;6153.2000839890525;6201.224846894138;6204.13142160098;6216.067589861466;6218.31598524162;6205.379462053849;6225.906611400833;6233;6248.375162483806;6256;4775.355327030807;4795.088933102699;4853.053654537332;4897.902041959141;4939.629527785431;4335.7181783184;4426.800793964279;4475.7985890635;4479.574440428154;4480.596746292849;5756.5682573807135;5760.452756988079;5780.566457515703;5797.159411885253;5831.241938548046;6085.4523092921845;6157.107219453154;6167.414095660905;6169;6179.81460556184;5564.554835613141;5609.971950140223;5670.631408958405;5733.283339582552;5802.390748971382;4705.752975461505;5270.472952704776;5324.7870085196155;5337.306150200449;5343.011542864582;6283.560150789401;6289.937100629027;6302.149209856702;6303.842403939755;6323.241211054703;6113.908291375722;6114.449440885012;6123.08153776937;6164.198654174928;6177.166082578974;6158.3841615838055;6222.782202622887;6264.123022776906;6265.749370025148;6268.091126786681;6123.357047510125;6139.2325959255095;6140.201773769381;6149.292831324455;6154.230721159855;6101.398774955092;6123.601965872204;6182.041783523561;6190.442860142609;6226.097215903759;4239.279322515118;5195.3246078009615;5893.32226793907;6067.24159480065;6117.877642447215;6080.695965201634;6083.908741368971;6099.84750381244;6124.203853499017;6135.1717518136265;5660.688662123623;5899.793507227227;5954.583179177416;5989.221401217814;5991.64050156992;6102.473814568429;6105.84735381619;6110.7861224856915;6125.785597504066;6128.8311429212345;4991.251312303184;5986.311574169026;5987.670678112746;5988.101784732151;6008.098785182257 18:13:35 INFO - PROCESS | 1674 | |1;ext.html;499.6677209655577;502.1739238951924;507.29232720355105;523.7878659143048;530.0856023359705;30519.389612207742;41406.792966035166;42483.575164248345;42532.99999999996;42579;43895.78052109743;44450.77774611131;44458;44465.55534444655;44484.55515444845;9337.906620933773;9568.904310956907;9577.042295770438;9583.424994500318;9586.712398628053;9294.767630809216;9428.151466368026;9445.480498572575;9463.290253231;9472.526373681289;9445.763855903622;9484.81030379392;9494.430334179971;9494.71515854522;9525.5713492893;9418.434893906388;9468.479233642147;9469.195118414944;9471.10024547667;9475.289353298496;9409.62361505539;9514.33399662024;9523.904760952371;9539.09378609031;9539.90460095397;34422;40495.59504404977;40973.97565060868;41136;41143.588564114274;3129.3741251749743;3193.7445004399824;3242.0598026572266;3322.734181265493;3692.3538380783257;29018.516133506393;29295.560566591517;29609.259768505744;30191.698083019328;30344.54483182754;2713.6472258606454;2727.5635898256382;2767.1836808141647;2798.6921438641734;2800.649918760155;530038;533623.6637633613;533767.3311633456;534097.3295133539;534358;33645.83177084099;34510.65489345125;61374.69312653498;61485.077723834176;61976.38023619796;36214.275714485564;36256.91229263126;36298;36305.27389452196;36476;15052.849471505146;15149.924250378897;15173.696526069416;15199.544013679608;15236.085834849722;12862.228266304051;12882.67793305175;12899.678057949472;12933.482660693468;12937.353132343345;7860.174681658458;7873.803154921173;7877.094134174643;7925.841483170304;7935.404844636675;3266.5100234964943;3269.640339562646;3277.901662950115;3293.127321259868;3294.7199488043307;811.0591713612182;955.8816185063492;1009.9848502272472;1045.9215558833118;1068.5992752717732;28626.71373286282;28870.566941495894;29413.411731765245;29673.554896676567;29735.25661858471;1047.1831971062584;1048.8059708953867;1065.280935368623;1103.25530267071;1159.5825502818977;12420.689482763004;14901.92549037248;14995.850041499445;15134.551363348146;15180.468683596022;660629;696730;704843;707373;708080;54825.725871370385;58157;58541.41458585445;58719.706401468575;58748.41251587515;1200.4837919694442;1292.9353532323346;1352.8850047745855;1408.9154650720989;1457.5773025822393;29073.41853162967;29642.407151856412;29711.85144074352;30250;30440.238994025327;50419;50963.235551466016;51107.21124760615;51155.232671510705;51213;469456;471007;471590;472597.63701181277;472937;25387.238382848544;26086.695665216284;26526.204213873614;26553.20340389714;26634.866825665747;45692.54307457016;46216;46380.536194638975;47039.764801177145;47505.28742068803;31951.520727188647;36618.267634646625;38202.808985956006;38805.611943881326;38941;36761;36849.44725829061;37085;37752;37823;37190.44214336733;37556.624433755314;37650.62349376471;38067.23865522619;38438.42342364923;27673.16980490508;30227.35409953511;33017.834910825295;33188.668113318556;33644.327113457104;23376.064957401515;24001.639975400733;24736.876315618305;24844.875775621007;24987.500249994533 18:13:35 INFO - PROCESS | 1674 | |2;jquery.html;313.81171297221664;391.1492503804227;391.25856501928837;404.71669831118226;412.08311506897155;18811.247550097996;19699.409017729457;22016.449588760268;22098.33704988849;22112.668309975365;11652.650420487378;11879.584214552493;12300.692482687937;12303;12309.138360314782;5095;5294.126469132589;5345.4387289334645;5347.304850369446;5349.545288650469;3827.464155018286;4023.6982226333;5611.691356975385;5937.079752638327;5939.4654481096695;5960.403959604027;5977.432143946318;5986.072158815369;5988.041913293874;5996.700164991755;6392.073149393358;6399.072134540489;6405.93594064058;6410.487161027107;6426.6144031358035;3706.592274849764;5320.334958130234;5670.149477578355;5676.148577713335;5713.171590119451;11013.118950483942;13011.934940325238;13157.539486118036;13322.86677133226;13432.328383580782;2056.50643845476;2065.5868826234814;2075.107703687414;2142.9035693393835;2246.3036458697693;9272.165505104573;9706.70879873605;9740.123388895034;9941.90058099417;9961.103500684972;1668.1325710630404;1673.6067024249292;1677.639307548883;1697.1853510315118;1818.0909545227387;112593;121209;121563.39218303941;122869;125146;8557.743267701979;10016.449095299828;11361.886381136248;11448.370339631234;11629.941850290863;14277.92861035709;14365.569032929028;14376.856231437761;14381.6404589884;14393.42426302957;8367.623456944451;8631.827363452696;8639.092895246034;8721.08428614999;8723;6967.965160174167;8568.871466928002;8626.309895208493;8706.39055266138;8737.694180703646;4510.503844577094;4511.977440112779;4514.88712782183;4525.122074339179;4581.633469322446;1755.7640763433565;1765.902875341843;1774.5918438759159;1836.9357072502398;1843.3824668736124;879.8152387998464;885.7342797160826;924.3206243411088;978.8776402949632;1005.3515482513884;19510.804891951182;19786;19811.900940495492;20076.09657565413;20247.595048098956;918.2103391083663;944.367273926471;966.4394651102484;995.3928103856641;1030.886642426178;10325.48372581368;10436.425996570262;10600.575976960989;10622.681319560426;10634.521446534924;596051;597099;608243;611930;612183;124461.75538244683;125829;128343;129708;131245.34377328242;905.4748246017294;999.5801763259449;1006.9395836249848;1009.8030883977701;1033.7674023344837;17217.053062081704;17319.393821216196;17513.03678297655;17616.55958601045;17729.202185901406;51510.22734659058;51531.48468515267;52921.7353913228;53378.73310633422;53868.19197711959;62759.6862015687;75407;76219.23780762122;76623.23376766162;77387.61306193433;7509.324160825552;7816.76549703487;7920.960395197987;8075.83848323042;8173.959130204549;11860;12074.335911524598;12268.570600028956;12393.690157745768;12507.124501284823;8724.265107910154;10722.626973890787;10872.728181795519;10946.835797462885;11011.339319640847;10417.635382761568;10430;10482.266241363031;10755.569777208824;10831.837522437327;10784.245102842726;10943.890561094287;11003.33979961205;11011.28426652265;11162.27445216091;6356.904646430215;7827.178146294557;7954.323882470055;8008;8236.176382361717;8813.735587932373;9340.71977840666;10785.245032847626;11070.778584428428;11143.554257829921 18:13:35 INFO - PROCESS | 1674 | |3;mootools.html;1411.4142630808215;1547.8761699064075;1577.7081239970607;1787.2761531579706;1937;7858.371330293577;8655.134486551342;8808.471491710494;8813.603387847546;9002.099790020995;8607.440516366447;8861.246794022516;8867.822643547126;8871.24594409476;8877.378583499158;2094.539201375695;2296.437372843656;2363.078399424226;2376.3465047112095;2394.4971555973266;2388.4267775733874;2413.0347860855622;2413.3122060212845;2422.86674232918;2424.5635785558593;2163.301808080657;2253.5014215546726;2371.2174982255815;2374.442006128558;2384.582698027847;2094.172801743318;2264.7169103862016;2337.672725818387;2354.7409784923643;2457.7542245775458;2354.587947109249;2396.940076498084;2398.424378149249;2399.1602938971396;2403.831731778776;8593.828123437559;8629.266512346458;8646.178613031752;8654.826903461959;8659.13408659129;5161.896762064775;5203.973980130113;5382.757775900094;5385.057614917374;5385.649932754398;3441.4665726812386;3500.387432199355;3537.665141006949;3573.857045718168;3580.7672501287334;993.9304248702593;1023.2428003277596;1027.019196667181;1039.1686650679426;1043.0768769638848;11145.777084458347;11394.25937314072;11402.942985285103;11424;11426.257293275994;4598.287265473857;4701.153792317347;4784.569388755029;4787.257975013878;4791.592714619227;9881.80236395268;9973.351732137387;9982.700518984442;9986.051325124101;9989.650362237287;5246;5329.067413202674;5330.200469929541;5333.946660533423;5334.1732031535175;4374.059577190856;4536.160810250114;4749.833755818529;4864.829730959399;4866.7566621668775;3919.568847426781;3964;4014.558398576154;4069.97965010179;4075.5924407559005;1125.5160281079052;1220.9755804883853;1282.7562763074986;1303.2050449225965;1322.821419108408;898.9370744047853;907.1427501011613;909.3498148823622;935.2435757910074;940.5673390240429;5696.572757043238;5855.934095928174;5888.499477544371;5928.644281343133;5973.158716928847;382.7454742596198;405.2118629266108;415.9417681524589;435.75597665307527;450.5314472948163;3793.070697679086;3860.7876566789096;3884.1454879926373;3959.4654721612214;3961.3661814109605;9983.950080249697;10444.791104177873;10453.9477302613;10457.790844183073;10504.684859454228;10060.647877324258;10433.895661043292;10450.425226612457;10458.215633827493;10472.842907356397;7934.365250779923;8107.756767296991;8135.837283254302;8156.877646835303;8158.959205204054;5785.710714464344;5849.122631605123;5918;5990.760369585168;6082.300535438481;9397.389169703947;9746.074122958307;9863.95068024684;9875.506224688881;9905.653302134391;9926.900730992598;10108.646197383056;10117.898821011695;10126.746831329276;10160.44117573511;6765.830854228684;6893.7931862044215;7017.859642807013;7438.888416673646;7636.389088872988;8134.349252059703;8403.411761176903;8415.242628163494;8418.074011858569;8427.662893484438;7246.130464344313;7481.027466429329;7535.623218838927;7554.395648348009;7595.6297752667415;7281.923888481714;7318.451116166202;7365.631718414165;7446.813829654302;7474.663640136316;6958.269381715049;7027.543209691354;7039.683214255473;7059.364657180879;7068;4755.762211889322;4756.381670382828;4909.3126962224605;4916.016796640614;5094.286799848099;4790.0419916016235;4906.607471402351;4948.480409556944;4968.801247950041;5096.388433388017 18:13:35 INFO - PROCESS | 1674 | |4;prototype.html;290.45104752018676;305.9648140463847;310.3931813304989;314.8724766469579;317.3335994411736;5092.898142037162;5231.947680523193;5338.932213557285;5355.4912283333115;5417.349918009844;5157.871053223662;5266.841994740154;5276.604254680895;5308.336457942757;5351.92961407719;3731.5708693500324;3840.5391353037535;3890.474785903895;4047.615476529725;4066.9389959150635;3266.4610339293986;3819.052283335491;3946.4080387941895;3984.880453586397;3986.8205930733043;3676.1361080146144;3963.346047902093;3969.3847453644585;3978.6220309070736;3985.9202815943663;3690.7970061646597;3713.7957412342175;3847.711421643388;3950.5654378018394;3969.8015099245213;2894.9276268093254;3433.5193072969805;3441.5392994009403;3448.827558622059;3463.1169051891857;4206.3690446432975;4999.825041115335;5000.274960130797;5012.699238045692;5038.823641172541;4016.377461493473;4282.314829627245;4566.862994110182;4581.358609794632;4601.148787474294;4289.541555870987;4347.586979236968;5524.254225679522;5530.751116199781;5598.916016259759;3200.9039728808175;3309.6855798699085;3814.5803961564484;3883.9223215535812;3972.1857019310974;4623;5608.971955140199;5856.76572937082;5892.970535147339;5946.554008449383;3040.2703351195555;3325.7339412846914;3403.6936675699308;3937.9212415751526;3952.4861767970556;3797.974546872329;3818;4007.699422543321;4013.979930100331;4044.69664775143;4611.677182597186;4626.190416677068;4628.629709623222;4645.628349732014;4651.906961860812;4619.838305659286;4936.753162341868;5185.377754669464;5524;5524.889502209933;1156.976860462803;1258.314218750783;1280.8142819291152;1359.9524016659568;1377.1874593989594;760.6120878351969;779.8401327727802;839.9370047246481;843.5571325054273;896.5875697179371;4673.719576825401;4936.531029552187;5175.14610089331;5227.686338819683;5250.579953603703;5016.398032236082;5675.978323901655;5697.686627235542;5719.456651618089;5722.399148089474;4768.165571025057;5162.716050617177;5171.637985341065;5187.665550502289;5215.948670028076;2641.098409145412;2935.897243596464;2992.62592175978;3001.3997200559975;3015.2461884528866;5096.464871188546;5622;5881.235439392852;5918.7928422505;5960.225170727777;4935.703857768545;5079.263506791494;5895.702945352015;5946.375630558729;5955.225820643375;5006.023825354021;5603.467670571371;6038.426349496791;6040.516758659384;6050.58142149684;5329.706866122246;5613.494785469164;5740.368559458373;5749.223854779551;5813.418658134153;4774.498677638937;4942.3080768693135;5597.880423915315;5612.186232996111;5628.493435590816;5588;5693.28833895763;5946.799684116904;6016.278046634432;6034.34055634704;3401.4387626041425;3659.396199627032;3757.0043938356903;3813.1229817141657;3825.445310430028;5131.563162314558;5613.354464236501;5665.943340566541;5682.43175682445;5730.1977723119635;5357.919631205457;5568.97215513936;5600.383957764561;5638.802641907513;5659.943400565941;4643.8374656886845;4923.655344125877;5235.947640523546;5577.693226872399;5635.239242702182;4907.9263811043575;4996.60027197816;5046.949530504648;5082.2974303607225;5399.352077750695;5316.867078323073;5339.866503337447;5356.866078348073;5365.490278423699;5370.570354371563;4320.740755554677;4926.482719314564;4937.185364414831;5447.346318441812;5471.8084867029465 18:13:35 INFO - PROCESS | 1674 | |5;yui.html;618.7679620142446;717.2218143314507;723.9963800180998;732.0885497555544;740.2338579570144;14871.851281487181;15089.6982060359;15366.539003829876;15601.765973510406;15693.607659808511;14370.06594571352;14417.423303067892;14484;14484.855151448483;14523.419063237458;697.7767114523355;723.2911746488445;867.7353407210799;885.5129678676718;904.010108930723;873.9868901966476;879.6833140069601;881.2729498164008;881.6781874615751;885.5395194498857;948.2461443152711;948.8956214816365;949.4872768704892;951.1867353412802;952.5618215620788;731.4623751542612;760.9923900760976;762.2529920677712;763.4159867701213;904.5296445848122;698.1691786773723;698.9161300643955;699.0597646165899;699.0632552379784;699.40899939551;14429.567112986522;14496;14500.782488262685;14504.202268875208;14504.27478626075;11254.212205145643;11882.168248222628;12217.389130543437;12318;12508.874911250952;9400.811983760286;10821.242513024092;10831.458427078693;10863.701750179804;10940.179486538542;4000.139969906484;4015.9799201003807;4017.3973903914352;4084.4281800547947;4151.813168407399;86330;86495;88818.5559072207;89280;91130;9305.860412093823;9567.425954442755;9745.463999480096;9772.413655180711;9810.068043535854;22075.779242207693;22226.44433889166;22295.554088918132;22342.106315747515;22411.88794056019;7335;7397;7458.366038886756;7506.968347336122;7725.5364678119495;3937.074787424953;4019.256437559061;4045.959540404558;4071.246819338432;4291.527931927485;3454.4472884338384;3560.1633616100194;3582.4089025311055;3586.8565257389414;3589.013021419131;623.9313675495691;629.2700467457727;644.7872202173271;647.0811447744214;647.456136845043;398.88631739954553;502.4046504891642;513.6455845466616;545.1931141909995;551.9199716041152;6844.828879278058;7366.631668416665;7706.730264440717;7760.30157285847;7846.882296765553;490.72274165096604;535.5715427657858;559.4685049203273;563.1215304125577;566.8464674387601;3620.7827530348263;3630.0743310455664;3645.635436456333;3725.554790212947;3802.5056742623283;78971;86209.70685439723;93407;93684.5947310791;95546.52226738822;75621;77961.61019194868;81619.18380816234;84276;84484.31031379338;73320.63339683268;73984.63007685034;74305;74404.62797685977;76335.61832190804;7148.783047628692;7214.386777124003;7223.530470519399;7292.453066019963;7568.356689681439;35716.28567428689;45919.85200370017;46067.76966115148;46514.534854652375;46576;61304.38695612987;61694;65564.67217664071;65777.35556611123;68342.31657683558;9591.664291749756;9882.50587470638;10028.849567256642;10078.143357814668;10218.233632477743;9161.038091000615;10069.123278134344;10413.219008574235;10424.843627345444;10942.17933654963;9675.951620242135;9726.70819875405;9749.463779492196;9890.109890109925;10225.897741022494;9605.711828645151;9690.273229507675;9771.169450596779;9906.306558540833;9917.851232231378;7833.49082309648;9439.858402123837;9512.809743805223;9573.99473055347;9619.134277915022;7342.889856652261;7457.47797654159;7562.054743157106;7564.962175189089;7622.847543049219;7478.551286922802;7870.520785952407;8384.413091083565;8704;8879 18:13:35 INFO - PROCESS | 1674 | __end_tp_report 18:13:35 INFO - PROCESS | 1674 | __start_cc_report 18:13:35 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1112 18:13:35 INFO - PROCESS | 1674 | __end_cc_report 18:13:35 INFO - PROCESS | 1674 | __startTimestamp1464311615899__endTimestamp 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | ------- Summary: start ------- 18:13:35 INFO - PROCESS | 1674 | Number of tests: 6 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5680.11 Median:5986.31 stddev:657.84 (11.0%) stddev-sans-first:625.24 18:13:35 INFO - PROCESS | 1674 | Values: 2995.7 3432.3 3447.8 3461.0 3496.0 4922.5 5545.3 5639.7 5639.8 5673.7 5679.6 5703.7 5793.9 5797.3 5831.0 5043.5 5072.9 5133.0 5139.6 5221.1 5189.5 5227.7 5241.5 5244.8 5271.1 5083.0 5087.5 5133.4 5163.1 5171.4 5137.4 5141.2 5158.9 5163.8 5223.9 5127.5 5144.4 5172.7 5182.8 5241.0 4674.8 5498.6 5593.7 5616.7 5708.5 6152.8 6204.7 6215.9 6225.1 6238.1 6152.1 6189.6 6210.3 6212.5 6223.7 5678.4 5708.5 5752.2 5786.6 5855.9 6214.6 6215.7 6229.0 6239.1 6240.6 6101.7 6132.7 6135.1 6140.3 6146.6 6101.7 6119.1 6124.9 6171.5 6190.5 6153.2 6201.2 6204.1 6216.1 6218.3 6205.4 6225.9 6233.0 6248.4 6256.0 4775.4 4795.1 4853.1 4897.9 4939.6 4335.7 4426.8 4475.8 4479.6 4480.6 5756.6 5760.5 5780.6 5797.2 5831.2 6085.5 6157.1 6167.4 6169.0 6179.8 5564.6 5610.0 5670.6 5733.3 5802.4 4705.8 5270.5 5324.8 5337.3 5343.0 6283.6 6289.9 6302.1 6303.8 6323.2 6113.9 6114.4 6123.1 6164.2 6177.2 6158.4 6222.8 6264.1 6265.7 6268.1 6123.4 6139.2 6140.2 6149.3 6154.2 6101.4 6123.6 6182.0 6190.4 6226.1 4239.3 5195.3 5893.3 6067.2 6117.9 6080.7 6083.9 6099.8 6124.2 6135.2 5660.7 5899.8 5954.6 5989.2 5991.6 6102.5 6105.8 6110.8 6125.8 6128.8 4991.3 5986.3 5987.7 5988.1 6008.1 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:68345.14 Median:37787.50 stddev:154098.34 (407.8%) stddev-sans-first:154446.43 18:13:35 INFO - PROCESS | 1674 | Values: 499.7 502.2 507.3 523.8 530.1 30519.4 41406.8 42483.6 42533.0 42579.0 43895.8 44450.8 44458.0 44465.6 44484.6 9337.9 9568.9 9577.0 9583.4 9586.7 9294.8 9428.2 9445.5 9463.3 9472.5 9445.8 9484.8 9494.4 9494.7 9525.6 9418.4 9468.5 9469.2 9471.1 9475.3 9409.6 9514.3 9523.9 9539.1 9539.9 34422.0 40495.6 40974.0 41136.0 41143.6 3129.4 3193.7 3242.1 3322.7 3692.4 29018.5 29295.6 29609.3 30191.7 30344.5 2713.6 2727.6 2767.2 2798.7 2800.6 530038.0 533623.7 533767.3 534097.3 534358.0 33645.8 34510.7 61374.7 61485.1 61976.4 36214.3 36256.9 36298.0 36305.3 36476.0 15052.8 15149.9 15173.7 15199.5 15236.1 12862.2 12882.7 12899.7 12933.5 12937.4 7860.2 7873.8 7877.1 7925.8 7935.4 3266.5 3269.6 3277.9 3293.1 3294.7 811.1 955.9 1010.0 1045.9 1068.6 28626.7 28870.6 29413.4 29673.6 29735.3 1047.2 1048.8 1065.3 1103.3 1159.6 12420.7 14901.9 14995.9 15134.6 15180.5 660629.0 696730.0 704843.0 707373.0 708080.0 54825.7 58157.0 58541.4 58719.7 58748.4 1200.5 1292.9 1352.9 1408.9 1457.6 29073.4 29642.4 29711.9 30250.0 30440.2 50419.0 50963.2 51107.2 51155.2 51213.0 469456.0 471007.0 471590.0 472597.6 472937.0 25387.2 26086.7 26526.2 26553.2 26634.9 45692.5 46216.0 46380.5 47039.8 47505.3 31951.5 36618.3 38202.8 38805.6 38941.0 36761.0 36849.4 37085.0 37752.0 37823.0 37190.4 37556.6 37650.6 38067.2 38438.4 27673.2 30227.4 33017.8 33188.7 33644.3 23376.1 24001.6 24736.9 24844.9 24987.5 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:34355.27 Median:1280.06 stddev:101100.55 (7898.1%) stddev-sans-first:101351.85 18:13:35 INFO - PROCESS | 1674 | Values: 313.8 391.1 391.3 404.7 412.1 18811.2 19699.4 22016.4 22098.3 22112.7 11652.7 11879.6 12300.7 12303.0 12309.1 5095.0 5294.1 5345.4 5347.3 5349.5 3827.5 4023.7 5611.7 5937.1 5939.5 5960.4 5977.4 5986.1 5988.0 5996.7 6392.1 6399.1 6405.9 6410.5 6426.6 3706.6 5320.3 5670.1 5676.1 5713.2 11013.1 13011.9 13157.5 13322.9 13432.3 2056.5 2065.6 2075.1 2142.9 2246.3 9272.2 9706.7 9740.1 9941.9 9961.1 1668.1 1673.6 1677.6 1697.2 1818.1 112593.0 121209.0 121563.4 122869.0 125146.0 8557.7 10016.4 11361.9 11448.4 11629.9 14277.9 14365.6 14376.9 14381.6 14393.4 8367.6 8631.8 8639.1 8721.1 8723.0 6968.0 8568.9 8626.3 8706.4 8737.7 4510.5 4512.0 4514.9 4525.1 4581.6 1755.8 1765.9 1774.6 1836.9 1843.4 879.8 885.7 924.3 978.9 1005.4 19510.8 19786.0 19811.9 20076.1 20247.6 918.2 944.4 966.4 995.4 1030.9 10325.5 10436.4 10600.6 10622.7 10634.5 596051.0 597099.0 608243.0 611930.0 612183.0 124461.8 125829.0 128343.0 129708.0 131245.3 905.5 999.6 1006.9 1009.8 1033.8 17217.1 17319.4 17513.0 17616.6 17729.2 51510.2 51531.5 52921.7 53378.7 53868.2 62759.7 75407.0 76219.2 76623.2 77387.6 7509.3 7816.8 7921.0 8075.8 8174.0 11860.0 12074.3 12268.6 12393.7 12507.1 8724.3 10722.6 10872.7 10946.8 11011.3 10417.6 10430.0 10482.3 10755.6 10831.8 10784.2 10943.9 11003.3 11011.3 11162.3 6356.9 7827.2 7954.3 8008.0 8236.2 8813.7 9340.7 10785.2 11070.8 11143.6 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5654.32 Median:4907.96 stddev:3168.03 (64.5%) stddev-sans-first:3160.87 18:13:35 INFO - PROCESS | 1674 | Values: 1411.4 1547.9 1577.7 1787.3 1937.0 7858.4 8655.1 8808.5 8813.6 9002.1 8607.4 8861.2 8867.8 8871.2 8877.4 2094.5 2296.4 2363.1 2376.3 2394.5 2388.4 2413.0 2413.3 2422.9 2424.6 2163.3 2253.5 2371.2 2374.4 2384.6 2094.2 2264.7 2337.7 2354.7 2457.8 2354.6 2396.9 2398.4 2399.2 2403.8 8593.8 8629.3 8646.2 8654.8 8659.1 5161.9 5204.0 5382.8 5385.1 5385.6 3441.5 3500.4 3537.7 3573.9 3580.8 993.9 1023.2 1027.0 1039.2 1043.1 11145.8 11394.3 11402.9 11424.0 11426.3 4598.3 4701.2 4784.6 4787.3 4791.6 9881.8 9973.4 9982.7 9986.1 9989.7 5246.0 5329.1 5330.2 5333.9 5334.2 4374.1 4536.2 4749.8 4864.8 4866.8 3919.6 3964.0 4014.6 4070.0 4075.6 1125.5 1221.0 1282.8 1303.2 1322.8 898.9 907.1 909.3 935.2 940.6 5696.6 5855.9 5888.5 5928.6 5973.2 382.7 405.2 415.9 435.8 450.5 3793.1 3860.8 3884.1 3959.5 3961.4 9984.0 10444.8 10453.9 10457.8 10504.7 10060.6 10433.9 10450.4 10458.2 10472.8 7934.4 8107.8 8135.8 8156.9 8159.0 5785.7 5849.1 5918.0 5990.8 6082.3 9397.4 9746.1 9864.0 9875.5 9905.7 9926.9 10108.6 10117.9 10126.7 10160.4 6765.8 6893.8 7017.9 7438.9 7636.4 8134.3 8403.4 8415.2 8418.1 8427.7 7246.1 7481.0 7535.6 7554.4 7595.6 7281.9 7318.5 7365.6 7446.8 7474.7 6958.3 7027.5 7039.7 7059.4 7068.0 4755.8 4756.4 4909.3 4916.0 5094.3 4790.0 4906.6 4948.5 4968.8 5096.4 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4458.95 Median:5003.15 stddev:1390.49 (27.8%) stddev-sans-first:1358.73 18:13:35 INFO - PROCESS | 1674 | Values: 290.5 306.0 310.4 314.9 317.3 5092.9 5231.9 5338.9 5355.5 5417.3 5157.9 5266.8 5276.6 5308.3 5351.9 3731.6 3840.5 3890.5 4047.6 4066.9 3266.5 3819.1 3946.4 3984.9 3986.8 3676.1 3963.3 3969.4 3978.6 3985.9 3690.8 3713.8 3847.7 3950.6 3969.8 2894.9 3433.5 3441.5 3448.8 3463.1 4206.4 4999.8 5000.3 5012.7 5038.8 4016.4 4282.3 4566.9 4581.4 4601.1 4289.5 4347.6 5524.3 5530.8 5598.9 3200.9 3309.7 3814.6 3883.9 3972.2 4623.0 5609.0 5856.8 5893.0 5946.6 3040.3 3325.7 3403.7 3937.9 3952.5 3798.0 3818.0 4007.7 4014.0 4044.7 4611.7 4626.2 4628.6 4645.6 4651.9 4619.8 4936.8 5185.4 5524.0 5524.9 1157.0 1258.3 1280.8 1360.0 1377.2 760.6 779.8 839.9 843.6 896.6 4673.7 4936.5 5175.1 5227.7 5250.6 5016.4 5676.0 5697.7 5719.5 5722.4 4768.2 5162.7 5171.6 5187.7 5215.9 2641.1 2935.9 2992.6 3001.4 3015.2 5096.5 5622.0 5881.2 5918.8 5960.2 4935.7 5079.3 5895.7 5946.4 5955.2 5006.0 5603.5 6038.4 6040.5 6050.6 5329.7 5613.5 5740.4 5749.2 5813.4 4774.5 4942.3 5597.9 5612.2 5628.5 5588.0 5693.3 5946.8 6016.3 6034.3 3401.4 3659.4 3757.0 3813.1 3825.4 5131.6 5613.4 5665.9 5682.4 5730.2 5357.9 5569.0 5600.4 5638.8 5659.9 4643.8 4923.7 5235.9 5577.7 5635.2 4907.9 4996.6 5046.9 5082.3 5399.4 5316.9 5339.9 5356.9 5365.5 5370.6 4320.7 4926.5 4937.2 5447.3 5471.8 18:13:35 INFO - PROCESS | 1674 | 18:13:35 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18045.75 Median:3933.00 stddev:26276.80 (668.1%) stddev-sans-first:26317.93 18:13:35 INFO - PROCESS | 1674 | Values: 618.8 717.2 724.0 732.1 740.2 14871.9 15089.7 15366.5 15601.8 15693.6 14370.1 14417.4 14484.0 14484.9 14523.4 697.8 723.3 867.7 885.5 904.0 874.0 879.7 881.3 881.7 885.5 948.2 948.9 949.5 951.2 952.6 731.5 761.0 762.3 763.4 904.5 698.2 698.9 699.1 699.1 699.4 14429.6 14496.0 14500.8 14504.2 14504.3 11254.2 11882.2 12217.4 12318.0 12508.9 9400.8 10821.2 10831.5 10863.7 10940.2 4000.1 4016.0 4017.4 4084.4 4151.8 86330.0 86495.0 88818.6 89280.0 91130.0 9305.9 9567.4 9745.5 9772.4 9810.1 22075.8 22226.4 22295.6 22342.1 22411.9 7335.0 7397.0 7458.4 7507.0 7725.5 3937.1 4019.3 4046.0 4071.2 4291.5 3454.4 3560.2 3582.4 3586.9 3589.0 623.9 629.3 644.8 647.1 647.5 398.9 502.4 513.6 545.2 551.9 6844.8 7366.6 7706.7 7760.3 7846.9 490.7 535.6 559.5 563.1 566.8 3620.8 3630.1 3645.6 3725.6 3802.5 78971.0 86209.7 93407.0 93684.6 95546.5 75621.0 77961.6 81619.2 84276.0 84484.3 73320.6 73984.6 74305.0 74404.6 76335.6 7148.8 7214.4 7223.5 7292.5 7568.4 35716.3 45919.9 46067.8 46514.5 46576.0 61304.4 61694.0 65564.7 65777.4 68342.3 9591.7 9882.5 10028.8 10078.1 10218.2 9161.0 10069.1 10413.2 10424.8 10942.2 9676.0 9726.7 9749.5 9890.1 10225.9 9605.7 9690.3 9771.2 9906.3 9917.9 7833.5 9439.9 9512.8 9574.0 9619.1 7342.9 7457.5 7562.1 7565.0 7622.8 7478.6 7870.5 8384.4 8704.0 8879.0 18:13:35 INFO - PROCESS | 1674 | -------- Summary: end -------- 18:13:35 INFO - PROCESS | 1674 | 18:13:36 INFO - TEST-INFO | 1674: exit 0 18:13:36 INFO - TEST-OK | dromaeo_css | took 1114917ms 18:13:36 INFO - TEST-START | kraken 18:13:36 INFO - operating with platform_type : mac_ 18:13:36 INFO - Initialising browser for kraken test... 18:13:36 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpnMsObV/profile http://localhost:49275/getInfo.html) 18:13:38 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 18:13:38 INFO - PROCESS | 1680 | colorDepth:24 18:13:38 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 18:13:38 INFO - PROCESS | 1680 | __metrics 18:13:38 INFO - PROCESS | 1680 | 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" 18:13:38 ERROR - PROCESS | 1680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:13:39 INFO - TEST-INFO | 1680: exit 0 18:13:39 INFO - Browser initialized. 18:13:39 INFO - Running cycle 1/1 for kraken test... 18:13:39 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/T/tmpnMsObV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:13:51 INFO - PROCESS | 1681 | RSS: Main: 241426432 18:13:51 INFO - PROCESS | 1681 | 18:13:53 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 18:13:54 INFO - PROCESS | 1681 | RSS: Main: 288780288 18:13:54 INFO - PROCESS | 1681 | 18:13:57 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 18:13:58 INFO - PROCESS | 1681 | RSS: Main: 297779200 18:13:58 INFO - PROCESS | 1681 | 18:14:01 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 18:14:02 INFO - PROCESS | 1681 | RSS: Main: 385855488 18:14:02 INFO - PROCESS | 1681 | 18:14:04 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 18:14:04 INFO - PROCESS | 1681 | RSS: Main: 289140736 18:14:04 INFO - PROCESS | 1681 | 18:14:06 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:14:07 INFO - PROCESS | 1681 | RSS: Main: 292937728 18:14:07 INFO - PROCESS | 1681 | 18:14:10 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 18:14:10 INFO - PROCESS | 1681 | RSS: Main: 329342976 18:14:10 INFO - PROCESS | 1681 | 18:14:13 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 18:14:14 INFO - PROCESS | 1681 | RSS: Main: 332877824 18:14:14 INFO - PROCESS | 1681 | 18:14:17 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 18:14:18 INFO - PROCESS | 1681 | RSS: Main: 332439552 18:14:18 INFO - PROCESS | 1681 | 18:14:19 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:14:20 INFO - PROCESS | 1681 | RSS: Main: 318488576 18:14:20 INFO - PROCESS | 1681 | 18:14:22 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:14:22 INFO - PROCESS | 1681 | RSS: Main: 325328896 18:14:22 INFO - PROCESS | 1681 | 18:14:24 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:14:25 INFO - PROCESS | 1681 | RSS: Main: 327036928 18:14:25 INFO - PROCESS | 1681 | 18:14:28 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:14:29 INFO - PROCESS | 1681 | RSS: Main: 328409088 18:14:29 INFO - PROCESS | 1681 | 18:14:31 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:14:32 INFO - PROCESS | 1681 | RSS: Main: 323919872 18:14:32 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 18:14:34 INFO - PROCESS | 1681 | RSS: Main: 370917376 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | __start_tp_report 18:14:34 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 18:14:34 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 18:14:34 INFO - PROCESS | 1681 | |i|pagename|runs| 18:14:34 INFO - PROCESS | 1681 | |0;ai-astar;113;107;113;113;113;112;113;110;113;112 18:14:34 INFO - PROCESS | 1681 | |1;audio-beat-detection;156;116;118;168;118;119;139;109;143;114 18:14:34 INFO - PROCESS | 1681 | |2;audio-dft;181;184;184;184;187;179;182;184;183;184 18:14:34 INFO - PROCESS | 1681 | |3;audio-fft;80;57;59;85;56;56;66;61;122;55 18:14:34 INFO - PROCESS | 1681 | |4;audio-oscillator;87;69;103;70;78;68;92;72;68;95 18:14:34 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;89;85;88;87;90;84;88;85;102;106 18:14:34 INFO - PROCESS | 1681 | |6;imaging-darkroom;93;93;95;101;149;94;95;96;94;97 18:14:34 INFO - PROCESS | 1681 | |7;imaging-desaturate;85;85;86;101;141;138;86;115;138;91 18:14:34 INFO - PROCESS | 1681 | |8;json-parse-financial;58;59;59;58;59;59;56;74;60;55 18:14:34 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;60;61;80;76;72;72;70;71;69;70 18:14:34 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;85;86;82;85;86;85;80;86;86;86 18:14:34 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;148;152;143;137;137;138;136;136;243;134 18:14:34 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;166;164;163;165;167;168;161;163;164;164 18:14:34 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;66;66;66;67;66;65;68;66;65;65 18:14:34 INFO - PROCESS | 1681 | __end_tp_report 18:14:34 INFO - PROCESS | 1681 | __start_cc_report 18:14:34 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,2000 18:14:34 INFO - PROCESS | 1681 | __end_cc_report 18:14:34 INFO - PROCESS | 1681 | __startTimestamp1464311674534__endTimestamp 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | ------- Summary: start ------- 18:14:34 INFO - PROCESS | 1681 | Number of tests: 14 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.00 stddev:1.97 (1.7%) stddev-sans-first:2.05 18:14:34 INFO - PROCESS | 1681 | Values: 113.0 107.0 113.0 113.0 113.0 112.0 113.0 110.0 113.0 112.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:129.00 stddev:20.20 (15.7%) stddev-sans-first:19.11 18:14:34 INFO - PROCESS | 1681 | Values: 156.0 116.0 118.0 168.0 118.0 119.0 139.0 109.0 143.0 114.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:2.15 (1.2%) stddev-sans-first:2.13 18:14:34 INFO - PROCESS | 1681 | Values: 181.0 184.0 184.0 184.0 187.0 179.0 182.0 184.0 183.0 184.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:69.70 Median:60.00 stddev:21.17 (35.3%) stddev-sans-first:22.12 18:14:34 INFO - PROCESS | 1681 | Values: 80.0 57.0 59.0 85.0 56.0 56.0 66.0 61.0 122.0 55.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:75.00 stddev:13.01 (17.3%) stddev-sans-first:13.57 18:14:34 INFO - PROCESS | 1681 | Values: 87.0 69.0 103.0 70.0 78.0 68.0 92.0 72.0 68.0 95.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:87.50 stddev:7.47 (8.5%) stddev-sans-first:7.91 18:14:34 INFO - PROCESS | 1681 | Values: 89.0 85.0 88.0 87.0 90.0 84.0 88.0 85.0 102.0 106.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:94.50 stddev:17.13 (18.1%) stddev-sans-first:17.95 18:14:34 INFO - PROCESS | 1681 | Values: 93.0 93.0 95.0 101.0 149.0 94.0 95.0 96.0 94.0 97.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:106.60 Median:85.00 stddev:24.23 (28.5%) stddev-sans-first:24.40 18:14:34 INFO - PROCESS | 1681 | Values: 85.0 85.0 86.0 101.0 141.0 138.0 86.0 115.0 138.0 91.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:59.00 stddev:5.25 (8.9%) stddev-sans-first:5.53 18:14:34 INFO - PROCESS | 1681 | Values: 58.0 59.0 59.0 58.0 59.0 59.0 56.0 74.0 60.0 55.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:71.50 stddev:6.03 (8.4%) stddev-sans-first:5.17 18:14:34 INFO - PROCESS | 1681 | Values: 60.0 61.0 80.0 76.0 72.0 72.0 70.0 71.0 69.0 70.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:86.00 stddev:2.06 (2.4%) stddev-sans-first:2.18 18:14:34 INFO - PROCESS | 1681 | Values: 85.0 86.0 82.0 85.0 86.0 85.0 80.0 86.0 86.0 86.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:140.50 stddev:33.06 (23.5%) stddev-sans-first:35.05 18:14:34 INFO - PROCESS | 1681 | Values: 148.0 152.0 143.0 137.0 137.0 138.0 136.0 136.0 243.0 134.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:164.50 stddev:2.07 (1.3%) stddev-sans-first:2.12 18:14:34 INFO - PROCESS | 1681 | Values: 166.0 164.0 163.0 165.0 167.0 168.0 161.0 163.0 164.0 164.0 18:14:34 INFO - PROCESS | 1681 | 18:14:34 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:0.94 (1.4%) stddev-sans-first:1.00 18:14:34 INFO - PROCESS | 1681 | Values: 66.0 66.0 66.0 67.0 66.0 65.0 68.0 66.0 65.0 65.0 18:14:34 INFO - PROCESS | 1681 | -------- Summary: end -------- 18:14:34 INFO - PROCESS | 1681 | 18:14:35 INFO - TEST-INFO | 1681: exit 0 18:14:35 INFO - TEST-OK | kraken | took 58874ms 18:14:35 INFO - SUITE-END | took 1173s 18:14:35 INFO - Completed test suite (00:19:34) 18:14:35 INFO - javascript benchmark 18:14:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2995.700429957004, 3432.262063656315, 3447.7586568940183, 3460.982695086524, 3495.9825200874006, 4922.532359425858, 5545.2513910622, 5639.66162030278, 5639.774409023634, 5673.716314184295, 5679.57403194761, 5703.74333155008, 5793.942060579393, 5797.275340582427, 5830.9504289227925, 5043.5208655177885, 5072.923906141411, 5132.97433512832, 5139.563137133348, 5221.138512145491, 5189.532942035216, 5227.738613069331, 5241.528262456378, 5244.816431424901, 5271.103912334893, 5083.0, 5087.465816089294, 5133.383993920754, 5163.1480805667015, 5171.405288391846, 5137.357830271216, 5141.15170996785, 5158.871028224325, 5163.845084647466, 5223.947760522384, 5127.5385215330425, 5144.4083930348115, 5172.715500647463, 5182.818601348972, 5240.973795131, 4674.813007479696, 5498.642588231752, 5593.720313984284, 5616.747246373923, 5708.486236238717, 6152.815415537496, 6204.720787564571, 6215.9378406216265, 6225.066240063981, 6238.1266622672865, 6152.107944348045, 6189.597676151036, 6210.254769427653, 6212.471939885115, 6223.719932603044, 5678.375378708339, 5708.514776244066, 5752.2234498342605, 5786.623869448473, 5855.912161317584, 6214.5960512566535, 6215.689215539205, 6229.0, 6239.06414037898, 6240.594361366497, 6101.654279549308, 6132.6933653317155, 6135.141080248769, 6140.263168419819, 6146.6004709693725, 6101.725422356005, 6119.106526814625, 6124.877502449926, 6171.47542458896, 6190.473809726133, 6153.2000839890525, 6201.224846894138, 6204.13142160098, 6216.067589861466, 6218.31598524162, 6205.379462053849, 6225.906611400833, 6233.0, 6248.375162483806, 6256.0, 4775.355327030807, 4795.088933102699, 4853.053654537332, 4897.902041959141, 4939.629527785431, 4335.7181783184, 4426.800793964279, 4475.7985890635, 4479.574440428154, 4480.596746292849, 5756.5682573807135, 5760.452756988079, 5780.566457515703, 5797.159411885253, 5831.241938548046, 6085.4523092921845, 6157.107219453154, 6167.414095660905, 6169.0, 6179.81460556184, 5564.554835613141, 5609.971950140223, 5670.631408958405, 5733.283339582552, 5802.390748971382, 4705.752975461505, 5270.472952704776, 5324.7870085196155, 5337.306150200449, 5343.011542864582, 6283.560150789401, 6289.937100629027, 6302.149209856702, 6303.842403939755, 6323.241211054703, 6113.908291375722, 6114.449440885012, 6123.08153776937, 6164.198654174928, 6177.166082578974, 6158.3841615838055, 6222.782202622887, 6264.123022776906, 6265.749370025148, 6268.091126786681, 6123.357047510125, 6139.2325959255095, 6140.201773769381, 6149.292831324455, 6154.230721159855, 6101.398774955092, 6123.601965872204, 6182.041783523561, 6190.442860142609, 6226.097215903759, 4239.279322515118, 5195.3246078009615, 5893.32226793907, 6067.24159480065, 6117.877642447215, 6080.695965201634, 6083.908741368971, 6099.84750381244, 6124.203853499017, 6135.1717518136265, 5660.688662123623, 5899.793507227227, 5954.583179177416, 5989.221401217814, 5991.64050156992, 6102.473814568429, 6105.84735381619, 6110.7861224856915, 6125.785597504066, 6128.8311429212345, 4991.251312303184, 5986.311574169026, 5987.670678112746, 5988.101784732151, 6008.098785182257], "value": 5638.914508864075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.6677209655577, 502.1739238951924, 507.29232720355105, 523.7878659143048, 530.0856023359705, 30519.389612207742, 41406.792966035166, 42483.575164248345, 42532.99999999996, 42579.0, 43895.78052109743, 44450.77774611131, 44458.0, 44465.55534444655, 44484.55515444845, 9337.906620933773, 9568.904310956907, 9577.042295770438, 9583.424994500318, 9586.712398628053, 9294.767630809216, 9428.151466368026, 9445.480498572575, 9463.290253231, 9472.526373681289, 9445.763855903622, 9484.81030379392, 9494.430334179971, 9494.71515854522, 9525.5713492893, 9418.434893906388, 9468.479233642147, 9469.195118414944, 9471.10024547667, 9475.289353298496, 9409.62361505539, 9514.33399662024, 9523.904760952371, 9539.09378609031, 9539.90460095397, 34422.0, 40495.59504404977, 40973.97565060868, 41136.0, 41143.588564114274, 3129.3741251749743, 3193.7445004399824, 3242.0598026572266, 3322.734181265493, 3692.3538380783257, 29018.516133506393, 29295.560566591517, 29609.259768505744, 30191.698083019328, 30344.54483182754, 2713.6472258606454, 2727.5635898256382, 2767.1836808141647, 2798.6921438641734, 2800.649918760155, 530038.0, 533623.6637633613, 533767.3311633456, 534097.3295133539, 534358.0, 33645.83177084099, 34510.65489345125, 61374.69312653498, 61485.077723834176, 61976.38023619796, 36214.275714485564, 36256.91229263126, 36298.0, 36305.27389452196, 36476.0, 15052.849471505146, 15149.924250378897, 15173.696526069416, 15199.544013679608, 15236.085834849722, 12862.228266304051, 12882.67793305175, 12899.678057949472, 12933.482660693468, 12937.353132343345, 7860.174681658458, 7873.803154921173, 7877.094134174643, 7925.841483170304, 7935.404844636675, 3266.5100234964943, 3269.640339562646, 3277.901662950115, 3293.127321259868, 3294.7199488043307, 811.0591713612182, 955.8816185063492, 1009.9848502272472, 1045.9215558833118, 1068.5992752717732, 28626.71373286282, 28870.566941495894, 29413.411731765245, 29673.554896676567, 29735.25661858471, 1047.1831971062584, 1048.8059708953867, 1065.280935368623, 1103.25530267071, 1159.5825502818977, 12420.689482763004, 14901.92549037248, 14995.850041499445, 15134.551363348146, 15180.468683596022, 660629.0, 696730.0, 704843.0, 707373.0, 708080.0, 54825.725871370385, 58157.0, 58541.41458585445, 58719.706401468575, 58748.41251587515, 1200.4837919694442, 1292.9353532323346, 1352.8850047745855, 1408.9154650720989, 1457.5773025822393, 29073.41853162967, 29642.407151856412, 29711.85144074352, 30250.0, 30440.238994025327, 50419.0, 50963.235551466016, 51107.21124760615, 51155.232671510705, 51213.0, 469456.0, 471007.0, 471590.0, 472597.63701181277, 472937.0, 25387.238382848544, 26086.695665216284, 26526.204213873614, 26553.20340389714, 26634.866825665747, 45692.54307457016, 46216.0, 46380.536194638975, 47039.764801177145, 47505.28742068803, 31951.520727188647, 36618.267634646625, 38202.808985956006, 38805.611943881326, 38941.0, 36761.0, 36849.44725829061, 37085.0, 37752.0, 37823.0, 37190.44214336733, 37556.624433755314, 37650.62349376471, 38067.23865522619, 38438.42342364923, 27673.16980490508, 30227.35409953511, 33017.834910825295, 33188.668113318556, 33644.327113457104, 23376.064957401515, 24001.639975400733, 24736.876315618305, 24844.875775621007, 24987.500249994533], "value": 18364.231450695406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.81171297221664, 391.1492503804227, 391.25856501928837, 404.71669831118226, 412.08311506897155, 18811.247550097996, 19699.409017729457, 22016.449588760268, 22098.33704988849, 22112.668309975365, 11652.650420487378, 11879.584214552493, 12300.692482687937, 12303.0, 12309.138360314782, 5095.0, 5294.126469132589, 5345.4387289334645, 5347.304850369446, 5349.545288650469, 3827.464155018286, 4023.6982226333, 5611.691356975385, 5937.079752638327, 5939.4654481096695, 5960.403959604027, 5977.432143946318, 5986.072158815369, 5988.041913293874, 5996.700164991755, 6392.073149393358, 6399.072134540489, 6405.93594064058, 6410.487161027107, 6426.6144031358035, 3706.592274849764, 5320.334958130234, 5670.149477578355, 5676.148577713335, 5713.171590119451, 11013.118950483942, 13011.934940325238, 13157.539486118036, 13322.86677133226, 13432.328383580782, 2056.50643845476, 2065.5868826234814, 2075.107703687414, 2142.9035693393835, 2246.3036458697693, 9272.165505104573, 9706.70879873605, 9740.123388895034, 9941.90058099417, 9961.103500684972, 1668.1325710630404, 1673.6067024249292, 1677.639307548883, 1697.1853510315118, 1818.0909545227387, 112593.0, 121209.0, 121563.39218303941, 122869.0, 125146.0, 8557.743267701979, 10016.449095299828, 11361.886381136248, 11448.370339631234, 11629.941850290863, 14277.92861035709, 14365.569032929028, 14376.856231437761, 14381.6404589884, 14393.42426302957, 8367.623456944451, 8631.827363452696, 8639.092895246034, 8721.08428614999, 8723.0, 6967.965160174167, 8568.871466928002, 8626.309895208493, 8706.39055266138, 8737.694180703646, 4510.503844577094, 4511.977440112779, 4514.88712782183, 4525.122074339179, 4581.633469322446, 1755.7640763433565, 1765.902875341843, 1774.5918438759159, 1836.9357072502398, 1843.3824668736124, 879.8152387998464, 885.7342797160826, 924.3206243411088, 978.8776402949632, 1005.3515482513884, 19510.804891951182, 19786.0, 19811.900940495492, 20076.09657565413, 20247.595048098956, 918.2103391083663, 944.367273926471, 966.4394651102484, 995.3928103856641, 1030.886642426178, 10325.48372581368, 10436.425996570262, 10600.575976960989, 10622.681319560426, 10634.521446534924, 596051.0, 597099.0, 608243.0, 611930.0, 612183.0, 124461.75538244683, 125829.0, 128343.0, 129708.0, 131245.34377328242, 905.4748246017294, 999.5801763259449, 1006.9395836249848, 1009.8030883977701, 1033.7674023344837, 17217.053062081704, 17319.393821216196, 17513.03678297655, 17616.55958601045, 17729.202185901406, 51510.22734659058, 51531.48468515267, 52921.7353913228, 53378.73310633422, 53868.19197711959, 62759.6862015687, 75407.0, 76219.23780762122, 76623.23376766162, 77387.61306193433, 7509.324160825552, 7816.76549703487, 7920.960395197987, 8075.83848323042, 8173.959130204549, 11860.0, 12074.335911524598, 12268.570600028956, 12393.690157745768, 12507.124501284823, 8724.265107910154, 10722.626973890787, 10872.728181795519, 10946.835797462885, 11011.339319640847, 10417.635382761568, 10430.0, 10482.266241363031, 10755.569777208824, 10831.837522437327, 10784.245102842726, 10943.890561094287, 11003.33979961205, 11011.28426652265, 11162.27445216091, 6356.904646430215, 7827.178146294557, 7954.323882470055, 8008.0, 8236.176382361717, 8813.735587932373, 9340.71977840666, 10785.245032847626, 11070.778584428428, 11143.554257829921], "value": 9003.883131076793, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1411.4142630808215, 1547.8761699064075, 1577.7081239970607, 1787.2761531579706, 1937.0, 7858.371330293577, 8655.134486551342, 8808.471491710494, 8813.603387847546, 9002.099790020995, 8607.440516366447, 8861.246794022516, 8867.822643547126, 8871.24594409476, 8877.378583499158, 2094.539201375695, 2296.437372843656, 2363.078399424226, 2376.3465047112095, 2394.4971555973266, 2388.4267775733874, 2413.0347860855622, 2413.3122060212845, 2422.86674232918, 2424.5635785558593, 2163.301808080657, 2253.5014215546726, 2371.2174982255815, 2374.442006128558, 2384.582698027847, 2094.172801743318, 2264.7169103862016, 2337.672725818387, 2354.7409784923643, 2457.7542245775458, 2354.587947109249, 2396.940076498084, 2398.424378149249, 2399.1602938971396, 2403.831731778776, 8593.828123437559, 8629.266512346458, 8646.178613031752, 8654.826903461959, 8659.13408659129, 5161.896762064775, 5203.973980130113, 5382.757775900094, 5385.057614917374, 5385.649932754398, 3441.4665726812386, 3500.387432199355, 3537.665141006949, 3573.857045718168, 3580.7672501287334, 993.9304248702593, 1023.2428003277596, 1027.019196667181, 1039.1686650679426, 1043.0768769638848, 11145.777084458347, 11394.25937314072, 11402.942985285103, 11424.0, 11426.257293275994, 4598.287265473857, 4701.153792317347, 4784.569388755029, 4787.257975013878, 4791.592714619227, 9881.80236395268, 9973.351732137387, 9982.700518984442, 9986.051325124101, 9989.650362237287, 5246.0, 5329.067413202674, 5330.200469929541, 5333.946660533423, 5334.1732031535175, 4374.059577190856, 4536.160810250114, 4749.833755818529, 4864.829730959399, 4866.7566621668775, 3919.568847426781, 3964.0, 4014.558398576154, 4069.97965010179, 4075.5924407559005, 1125.5160281079052, 1220.9755804883853, 1282.7562763074986, 1303.2050449225965, 1322.821419108408, 898.9370744047853, 907.1427501011613, 909.3498148823622, 935.2435757910074, 940.5673390240429, 5696.572757043238, 5855.934095928174, 5888.499477544371, 5928.644281343133, 5973.158716928847, 382.7454742596198, 405.2118629266108, 415.9417681524589, 435.75597665307527, 450.5314472948163, 3793.070697679086, 3860.7876566789096, 3884.1454879926373, 3959.4654721612214, 3961.3661814109605, 9983.950080249697, 10444.791104177873, 10453.9477302613, 10457.790844183073, 10504.684859454228, 10060.647877324258, 10433.895661043292, 10450.425226612457, 10458.215633827493, 10472.842907356397, 7934.365250779923, 8107.756767296991, 8135.837283254302, 8156.877646835303, 8158.959205204054, 5785.710714464344, 5849.122631605123, 5918.0, 5990.760369585168, 6082.300535438481, 9397.389169703947, 9746.074122958307, 9863.95068024684, 9875.506224688881, 9905.653302134391, 9926.900730992598, 10108.646197383056, 10117.898821011695, 10126.746831329276, 10160.44117573511, 6765.830854228684, 6893.7931862044215, 7017.859642807013, 7438.888416673646, 7636.389088872988, 8134.349252059703, 8403.411761176903, 8415.242628163494, 8418.074011858569, 8427.662893484438, 7246.130464344313, 7481.027466429329, 7535.623218838927, 7554.395648348009, 7595.6297752667415, 7281.923888481714, 7318.451116166202, 7365.631718414165, 7446.813829654302, 7474.663640136316, 6958.269381715049, 7027.543209691354, 7039.683214255473, 7059.364657180879, 7068.0, 4755.762211889322, 4756.381670382828, 4909.3126962224605, 4916.016796640614, 5094.286799848099, 4790.0419916016235, 4906.607471402351, 4948.480409556944, 4968.801247950041, 5096.388433388017], "value": 4464.345598379549, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.45104752018676, 305.9648140463847, 310.3931813304989, 314.8724766469579, 317.3335994411736, 5092.898142037162, 5231.947680523193, 5338.932213557285, 5355.4912283333115, 5417.349918009844, 5157.871053223662, 5266.841994740154, 5276.604254680895, 5308.336457942757, 5351.92961407719, 3731.5708693500324, 3840.5391353037535, 3890.474785903895, 4047.615476529725, 4066.9389959150635, 3266.4610339293986, 3819.052283335491, 3946.4080387941895, 3984.880453586397, 3986.8205930733043, 3676.1361080146144, 3963.346047902093, 3969.3847453644585, 3978.6220309070736, 3985.9202815943663, 3690.7970061646597, 3713.7957412342175, 3847.711421643388, 3950.5654378018394, 3969.8015099245213, 2894.9276268093254, 3433.5193072969805, 3441.5392994009403, 3448.827558622059, 3463.1169051891857, 4206.3690446432975, 4999.825041115335, 5000.274960130797, 5012.699238045692, 5038.823641172541, 4016.377461493473, 4282.314829627245, 4566.862994110182, 4581.358609794632, 4601.148787474294, 4289.541555870987, 4347.586979236968, 5524.254225679522, 5530.751116199781, 5598.916016259759, 3200.9039728808175, 3309.6855798699085, 3814.5803961564484, 3883.9223215535812, 3972.1857019310974, 4623.0, 5608.971955140199, 5856.76572937082, 5892.970535147339, 5946.554008449383, 3040.2703351195555, 3325.7339412846914, 3403.6936675699308, 3937.9212415751526, 3952.4861767970556, 3797.974546872329, 3818.0, 4007.699422543321, 4013.979930100331, 4044.69664775143, 4611.677182597186, 4626.190416677068, 4628.629709623222, 4645.628349732014, 4651.906961860812, 4619.838305659286, 4936.753162341868, 5185.377754669464, 5524.0, 5524.889502209933, 1156.976860462803, 1258.314218750783, 1280.8142819291152, 1359.9524016659568, 1377.1874593989594, 760.6120878351969, 779.8401327727802, 839.9370047246481, 843.5571325054273, 896.5875697179371, 4673.719576825401, 4936.531029552187, 5175.14610089331, 5227.686338819683, 5250.579953603703, 5016.398032236082, 5675.978323901655, 5697.686627235542, 5719.456651618089, 5722.399148089474, 4768.165571025057, 5162.716050617177, 5171.637985341065, 5187.665550502289, 5215.948670028076, 2641.098409145412, 2935.897243596464, 2992.62592175978, 3001.3997200559975, 3015.2461884528866, 5096.464871188546, 5622.0, 5881.235439392852, 5918.7928422505, 5960.225170727777, 4935.703857768545, 5079.263506791494, 5895.702945352015, 5946.375630558729, 5955.225820643375, 5006.023825354021, 5603.467670571371, 6038.426349496791, 6040.516758659384, 6050.58142149684, 5329.706866122246, 5613.494785469164, 5740.368559458373, 5749.223854779551, 5813.418658134153, 4774.498677638937, 4942.3080768693135, 5597.880423915315, 5612.186232996111, 5628.493435590816, 5588.0, 5693.28833895763, 5946.799684116904, 6016.278046634432, 6034.34055634704, 3401.4387626041425, 3659.396199627032, 3757.0043938356903, 3813.1229817141657, 3825.445310430028, 5131.563162314558, 5613.354464236501, 5665.943340566541, 5682.43175682445, 5730.1977723119635, 5357.919631205457, 5568.97215513936, 5600.383957764561, 5638.802641907513, 5659.943400565941, 4643.8374656886845, 4923.655344125877, 5235.947640523546, 5577.693226872399, 5635.239242702182, 4907.9263811043575, 4996.60027197816, 5046.949530504648, 5082.2974303607225, 5399.352077750695, 5316.867078323073, 5339.866503337447, 5356.866078348073, 5365.490278423699, 5370.570354371563, 4320.740755554677, 4926.482719314564, 4937.185364414831, 5447.346318441812, 5471.8084867029465], "value": 4015.07790985423, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.7679620142446, 717.2218143314507, 723.9963800180998, 732.0885497555544, 740.2338579570144, 14871.851281487181, 15089.6982060359, 15366.539003829876, 15601.765973510406, 15693.607659808511, 14370.06594571352, 14417.423303067892, 14484.0, 14484.855151448483, 14523.419063237458, 697.7767114523355, 723.2911746488445, 867.7353407210799, 885.5129678676718, 904.010108930723, 873.9868901966476, 879.6833140069601, 881.2729498164008, 881.6781874615751, 885.5395194498857, 948.2461443152711, 948.8956214816365, 949.4872768704892, 951.1867353412802, 952.5618215620788, 731.4623751542612, 760.9923900760976, 762.2529920677712, 763.4159867701213, 904.5296445848122, 698.1691786773723, 698.9161300643955, 699.0597646165899, 699.0632552379784, 699.40899939551, 14429.567112986522, 14496.0, 14500.782488262685, 14504.202268875208, 14504.27478626075, 11254.212205145643, 11882.168248222628, 12217.389130543437, 12318.0, 12508.874911250952, 9400.811983760286, 10821.242513024092, 10831.458427078693, 10863.701750179804, 10940.179486538542, 4000.139969906484, 4015.9799201003807, 4017.3973903914352, 4084.4281800547947, 4151.813168407399, 86330.0, 86495.0, 88818.5559072207, 89280.0, 91130.0, 9305.860412093823, 9567.425954442755, 9745.463999480096, 9772.413655180711, 9810.068043535854, 22075.779242207693, 22226.44433889166, 22295.554088918132, 22342.106315747515, 22411.88794056019, 7335.0, 7397.0, 7458.366038886756, 7506.968347336122, 7725.5364678119495, 3937.074787424953, 4019.256437559061, 4045.959540404558, 4071.246819338432, 4291.527931927485, 3454.4472884338384, 3560.1633616100194, 3582.4089025311055, 3586.8565257389414, 3589.013021419131, 623.9313675495691, 629.2700467457727, 644.7872202173271, 647.0811447744214, 647.456136845043, 398.88631739954553, 502.4046504891642, 513.6455845466616, 545.1931141909995, 551.9199716041152, 6844.828879278058, 7366.631668416665, 7706.730264440717, 7760.30157285847, 7846.882296765553, 490.72274165096604, 535.5715427657858, 559.4685049203273, 563.1215304125577, 566.8464674387601, 3620.7827530348263, 3630.0743310455664, 3645.635436456333, 3725.554790212947, 3802.5056742623283, 78971.0, 86209.70685439723, 93407.0, 93684.5947310791, 95546.52226738822, 75621.0, 77961.61019194868, 81619.18380816234, 84276.0, 84484.31031379338, 73320.63339683268, 73984.63007685034, 74305.0, 74404.62797685977, 76335.61832190804, 7148.783047628692, 7214.386777124003, 7223.530470519399, 7292.453066019963, 7568.356689681439, 35716.28567428689, 45919.85200370017, 46067.76966115148, 46514.534854652375, 46576.0, 61304.38695612987, 61694.0, 65564.67217664071, 65777.35556611123, 68342.31657683558, 9591.664291749756, 9882.50587470638, 10028.849567256642, 10078.143357814668, 10218.233632477743, 9161.038091000615, 10069.123278134344, 10413.219008574235, 10424.843627345444, 10942.17933654963, 9675.951620242135, 9726.70819875405, 9749.463779492196, 9890.109890109925, 10225.897741022494, 9605.711828645151, 9690.273229507675, 9771.169450596779, 9906.306558540833, 9917.851232231378, 7833.49082309648, 9439.858402123837, 9512.809743805223, 9573.99473055347, 9619.134277915022, 7342.889856652261, 7457.47797654159, 7562.054743157106, 7564.962175189089, 7622.847543049219, 7478.551286922802, 7870.520785952407, 8384.413091083565, 8704.0, 8879.0], "value": 6572.281640776671, "unit": "score"}], "name": "dromaeo_css", "value": 6920.460462648613, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 107.0, 113.0, 113.0, 113.0, 112.0, 113.0, 110.0, 113.0, 112.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 116.0, 118.0, 168.0, 118.0, 119.0, 139.0, 109.0, 143.0, 114.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 184.0, 184.0, 187.0, 179.0, 182.0, 184.0, 183.0, 184.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 59.0, 85.0, 56.0, 56.0, 66.0, 61.0, 122.0, 55.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 103.0, 70.0, 78.0, 68.0, 92.0, 72.0, 68.0, 95.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 87.0, 90.0, 84.0, 88.0, 85.0, 102.0, 106.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 95.0, 101.0, 149.0, 94.0, 95.0, 96.0, 94.0, 97.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 101.0, 141.0, 138.0, 86.0, 115.0, 138.0, 91.0], "value": 106.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 59.0, 58.0, 59.0, 59.0, 56.0, 74.0, 60.0, 55.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 80.0, 76.0, 72.0, 72.0, 70.0, 71.0, 69.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 82.0, 85.0, 86.0, 85.0, 80.0, 86.0, 86.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 143.0, 137.0, 137.0, 138.0, 136.0, 136.0, 243.0, 134.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 163.0, 165.0, 167.0, 168.0, 161.0, 163.0, 164.0, 164.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 68.0, 66.0, 65.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1468.1000000000001, "alertThreshold": 2.0}]} 18:14:35 INFO - Return code: 0 18:14:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:14:35 ERROR - # TBPL SUCCESS # 18:14:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:14:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:14:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:14:35 INFO - 2016-05-26 18:14:35.867 screenresolution[1685:12575] starting screenresolution argv=screenresolution get 18:14:35 INFO - 2016-05-26 18:14:35.869 screenresolution[1685:12575] Display 0: 1600x1200x32@60 18:14:35 INFO - 2016-05-26 18:14:35.875 screenresolution[1686:12578] starting screenresolution argv=screenresolution list 18:14:35 INFO - Available Modes on Display 0 18:14:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:14:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:14:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:14:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:14:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:14:36 INFO - Intel Iris: 18:14:36 INFO - Chipset Model: Intel Iris 18:14:36 INFO - Type: GPU 18:14:36 INFO - Bus: Built-In 18:14:36 INFO - VRAM (Dynamic, Max): 1536 MB 18:14:36 INFO - Vendor: Intel (0x8086) 18:14:36 INFO - Device ID: 0x0a2e 18:14:36 INFO - Revision ID: 0x0009 18:14:36 INFO - Displays: 18:14:36 INFO - Display: 18:14:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:14:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:14:36 INFO - Display Serial Number: 1600x1200 60 18:14:36 INFO - Main Display: Yes 18:14:36 INFO - Mirror: Off 18:14:36 INFO - Online: Yes 18:14:36 INFO - Rotation: Supported 18:14:36 INFO - Return code: 0 18:14:36 INFO - Running post-action listener: _resource_record_post_action 18:14:36 INFO - ##### 18:14:36 INFO - ##### Finished run-tests step (success) 18:14:36 INFO - ##### 18:14:36 INFO - Running post-run listener: _resource_record_post_run 18:14:36 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 31444480; Write bytes: 696973312; Read time: 194; Write time: 1928 18:14:36 INFO - TinderboxPrint: CPU usage
53.6% 18:14:36 INFO - TinderboxPrint: I/O read bytes / time
31,444,480 / 194 18:14:36 INFO - TinderboxPrint: I/O write bytes / time
696,973,312 / 1,928 18:14:36 INFO - TinderboxPrint: CPU idle
2,246.0 (46.4%) 18:14:36 INFO - TinderboxPrint: CPU system
956.7 (19.8%) 18:14:36 INFO - TinderboxPrint: CPU user
1,635.1 (33.8%) 18:14:36 INFO - TinderboxPrint: Swap in / out
424,660,992 / 0 18:14:36 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 378663424; Write bytes: 373278720; Read time: 27989; Write time: 761 18:14:36 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30522880; Write bytes: 296640512; Read time: 184; Write time: 1101 18:14:36 INFO - Running post-run listener: _upload_blobber_files 18:14:36 INFO - Blob upload gear active. 18:14:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:14:36 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 18:14:36 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'] 18:14:36 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 18:14:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:14:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:14:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:49 INFO - (blobuploader) - INFO - Done attempting. 18:14:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:14:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:14:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:50 INFO - (blobuploader) - INFO - Done attempting. 18:14:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:14:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:14:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:14:53 INFO - (blobuploader) - INFO - Done attempting. 18:14:53 INFO - (blobuploader) - INFO - Iteration through files over. 18:14:53 INFO - Return code: 0 18:14:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:14:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:14:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/510c1b6a9e4bb158fd5115e8238dd46875db8ad81ede5d2d76c83646fe7d002f875b47d56e4a0f8296f0a8c18d9b3f338d7761d289d2c3d3657dabb185401f93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9440d093984c272a0ec3f7d69202a7b35ac59a99286faada076eeb51c3deca2d196c2599198a0176880ac9e3bb90bc42352643e2ae8532f0214a142e9d3acf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab4ffe7ca3220b2af39105f1b7b3d66db2438a4e768aeb133e55682569766198c1cfeaad61b9c54a0f84f2ba65436b8effbb6c19374d5dd8af8401ee4a602315"} 18:14:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:14:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:14:53 INFO - Contents: 18:14:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/510c1b6a9e4bb158fd5115e8238dd46875db8ad81ede5d2d76c83646fe7d002f875b47d56e4a0f8296f0a8c18d9b3f338d7761d289d2c3d3657dabb185401f93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9440d093984c272a0ec3f7d69202a7b35ac59a99286faada076eeb51c3deca2d196c2599198a0176880ac9e3bb90bc42352643e2ae8532f0214a142e9d3acf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab4ffe7ca3220b2af39105f1b7b3d66db2438a4e768aeb133e55682569766198c1cfeaad61b9c54a0f84f2ba65436b8effbb6c19374d5dd8af8401ee4a602315"} 18:14:53 INFO - Running post-run listener: copy_logs_to_upload_dir 18:14:53 INFO - Copying logs to upload dir... 18:14:53 INFO - mkdir: /builds/slave/test/build/upload/logs 18:14:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1291.945523 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 32 secs) (at 2016-05-26 18:14:53.742828) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 18:14:53.746776) ========= 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.0CLLkwBv9H/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.bXggMFIWab/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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/510c1b6a9e4bb158fd5115e8238dd46875db8ad81ede5d2d76c83646fe7d002f875b47d56e4a0f8296f0a8c18d9b3f338d7761d289d2c3d3657dabb185401f93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9440d093984c272a0ec3f7d69202a7b35ac59a99286faada076eeb51c3deca2d196c2599198a0176880ac9e3bb90bc42352643e2ae8532f0214a142e9d3acf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab4ffe7ca3220b2af39105f1b7b3d66db2438a4e768aeb133e55682569766198c1cfeaad61b9c54a0f84f2ba65436b8effbb6c19374d5dd8af8401ee4a602315"} build_url:https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/EXTBD4snSNKct40W8AI4lQ/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/510c1b6a9e4bb158fd5115e8238dd46875db8ad81ede5d2d76c83646fe7d002f875b47d56e4a0f8296f0a8c18d9b3f338d7761d289d2c3d3657dabb185401f93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b9440d093984c272a0ec3f7d69202a7b35ac59a99286faada076eeb51c3deca2d196c2599198a0176880ac9e3bb90bc42352643e2ae8532f0214a142e9d3acf6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab4ffe7ca3220b2af39105f1b7b3d66db2438a4e768aeb133e55682569766198c1cfeaad61b9c54a0f84f2ba65436b8effbb6c19374d5dd8af8401ee4a602315"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 18:14:53.780673) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 18:14:53.780999) ========= 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.0CLLkwBv9H/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.bXggMFIWab/Listeners TMPDIR=/var/folders/nt/w1wt5wh94cg0328j1nmkplv400000w/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.005090 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 18:14:53.829872) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 18:14:53.830142) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 18:14:53.830439) ========= ========= Total master_lag: 0.46 =========