builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0177 starttime: 1464397749.7 results: success (0) buildid: 20160527161740 builduid: 37a6d34321b142ecb945c297c69c8570 revision: 1bd815acf6d3d51ded73a0ffbe05bd8a2c515784 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:09.702589) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:09.703334) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:09.703607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:09.736603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:09.736896) ========= 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.HAeEojBL0a/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.R62gLrl6cs/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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-27 18:09:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.4M=0.001s 2016-05-27 18:09:10 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.532855 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:10.297894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:10.298186) ========= 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.HAeEojBL0a/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.R62gLrl6cs/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.038132 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:10.354865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 18:09:10.355231) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1bd815acf6d3d51ded73a0ffbe05bd8a2c515784 --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 1bd815acf6d3d51ded73a0ffbe05bd8a2c515784 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HAeEojBL0a/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.R62gLrl6cs/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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-27 18:09:10,429 truncating revision to first 12 chars 2016-05-27 18:09:10,429 Setting DEBUG logging. 2016-05-27 18:09:10,429 attempt 1/10 2016-05-27 18:09:10,429 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1bd815acf6d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 18:09:11,478 unpacking tar archive at: fx-team-1bd815acf6d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.321911 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 18:09:11.696109) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:11.696387) ========= script_repo_revision: 1bd815acf6d3d51ded73a0ffbe05bd8a2c515784 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:11.696757) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:11.697032) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 18:09:11.714162) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-27 18:09:11.714426) ========= /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.HAeEojBL0a/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.R62gLrl6cs/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:09:11 INFO - MultiFileLogger online at 20160527 18:09:11 in /builds/slave/test 18:09:11 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 18:09:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:09:11 INFO - {'append_to_log': False, 18:09:11 INFO - 'base_work_dir': '/builds/slave/test', 18:09:11 INFO - 'blob_upload_branch': 'Fx-Team', 18:09:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:09:11 INFO - 'branch': 'Fx-Team', 18:09:11 INFO - 'buildbot_json_path': 'buildprops.json', 18:09:11 INFO - 'config_files': ('talos/mac_config.py',), 18:09:11 INFO - 'default_actions': ('clobber', 18:09:11 INFO - 'read-buildbot-config', 18:09:11 INFO - 'download-and-extract', 18:09:11 INFO - 'populate-webroot', 18:09:11 INFO - 'create-virtualenv', 18:09:11 INFO - 'install', 18:09:11 INFO - 'run-tests'), 18:09:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:09:11 INFO - 'download_minidump_stackwalk': True, 18:09:11 INFO - 'download_symbols': 'ondemand', 18:09:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:09:11 INFO - 'tooltool.py': '/tools/tooltool.py', 18:09:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:09:11 INFO - '/tools/misc-python/virtualenv.py')}, 18:09:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:09:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:09:11 INFO - 'installer_path': 'installer.exe', 18:09:11 INFO - 'log_level': 'info', 18:09:11 INFO - 'log_name': 'talos', 18:09:11 INFO - 'log_to_console': True, 18:09:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:09:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:09:11 INFO - 'opt_config_files': (), 18:09:11 INFO - 'pip_index': False, 18:09:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:09:11 INFO - 'cmd': ('bash', 18:09:11 INFO - '-c', 18:09:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:09:11 INFO - 'enabled': True, 18:09:11 INFO - 'halt_on_failure': False, 18:09:11 INFO - 'name': 'check_screen_resolution'},), 18:09:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:09:11 INFO - 'cmd': ('bash', 18:09:11 INFO - '-c', 18:09:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:09:11 INFO - 'enabled': True, 18:09:11 INFO - 'halt_on_failure': False, 18:09:11 INFO - 'name': 'check_screen_resolution'},), 18:09:11 INFO - 'run_cmd_checks_enabled': True, 18:09:11 INFO - 'sps_profile': False, 18:09:11 INFO - 'sps_profile_interval': 0, 18:09:11 INFO - 'suite': 'dromaeojs', 18:09:11 INFO - 'system_bits': '32', 18:09:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:09:11 INFO - 'title': 't-yosemite-r7-0177', 18:09:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:09:11 INFO - 'use_talos_json': True, 18:09:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:09:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:09:11 INFO - 'work_dir': 'build'} 18:09:11 INFO - ##### 18:09:11 INFO - ##### Running clobber step. 18:09:11 INFO - ##### 18:09:11 INFO - Running pre-action listener: _resource_record_pre_action 18:09:11 INFO - Running main action method: clobber 18:09:11 INFO - rmtree: /builds/slave/test/build 18:09:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:09:13 INFO - Running post-action listener: _resource_record_post_action 18:09:13 INFO - ##### 18:09:13 INFO - ##### Finished clobber step (success) 18:09:13 INFO - ##### 18:09:13 INFO - ##### 18:09:13 INFO - ##### Running read-buildbot-config step. 18:09:13 INFO - ##### 18:09:13 INFO - Running pre-action listener: _resource_record_pre_action 18:09:13 INFO - Running main action method: read_buildbot_config 18:09:13 INFO - Using buildbot properties: 18:09:13 INFO - { 18:09:13 INFO - "project": "", 18:09:13 INFO - "product": "firefox", 18:09:13 INFO - "script_repo_revision": "production", 18:09:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:09:13 INFO - "repository": "", 18:09:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:09:13 INFO - "buildid": "20160527161740", 18:09:13 INFO - "pgo_build": "False", 18:09:13 INFO - "basedir": "/builds/slave/test", 18:09:13 INFO - "buildnumber": 42, 18:09:13 INFO - "slavename": "t-yosemite-r7-0177", 18:09:13 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:09:13 INFO - "platform": "yosemite_r7", 18:09:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:09:13 INFO - "branch": "fx-team", 18:09:13 INFO - "repo_path": "integration/fx-team", 18:09:13 INFO - "revision": "1bd815acf6d3d51ded73a0ffbe05bd8a2c515784", 18:09:13 INFO - "stage_platform": "macosx64", 18:09:13 INFO - "builduid": "37a6d34321b142ecb945c297c69c8570", 18:09:13 INFO - "slavebuilddir": "test" 18:09:13 INFO - } 18:09:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:09:13 INFO - Running post-action listener: _resource_record_post_action 18:09:13 INFO - ##### 18:09:13 INFO - ##### Finished read-buildbot-config step (success) 18:09:13 INFO - ##### 18:09:13 INFO - ##### 18:09:13 INFO - ##### Running download-and-extract step. 18:09:13 INFO - ##### 18:09:13 INFO - Running pre-action listener: _resource_record_pre_action 18:09:13 INFO - Running main action method: download_and_extract 18:09:13 INFO - mkdir: /builds/slave/test/build/tests 18:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:13 INFO - https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:09:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:09:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:09:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:31 INFO - Downloaded 1199 bytes. 18:09:31 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:09:31 INFO - Using the following test package requirements: 18:09:31 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:09:31 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:09:31 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:09:31 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'jsshell-mac.zip'], 18:09:31 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:09:31 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:09:31 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:09:31 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:09:31 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:09:31 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:09:31 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:09:31 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:09:31 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')]} 18:09:31 INFO - https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:09:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:09:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:09:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:35 INFO - Downloaded 18684782 bytes. 18:09:35 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'] 18:09:35 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 18:09:38 INFO - Return code: 0 18:09:38 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 18:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:38 INFO - https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:09:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:09:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:09:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:39 INFO - Downloaded 18684782 bytes. 18:09:39 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'] 18:09:39 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 18:09:43 INFO - Return code: 0 18:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:43 INFO - https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:09:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:09:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:09:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:44 INFO - Downloaded 10834034 bytes. 18:09:44 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'] 18:09:44 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 18:09:44 INFO - Return code: 0 18:09: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')]} 18:09:44 INFO - https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:09:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:09:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:09:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QgfadrGDQCusj3AK60Zj9g/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 18:09:47 INFO - Downloaded 110447461 bytes. 18:09:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:09:47 INFO - mkdir: /builds/slave/test/properties 18:09:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:09:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:09:47 INFO - Contents: 18:09:47 INFO - build_url:https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:09:49 INFO - Running post-action listener: _resource_record_post_action 18:09:49 INFO - Running post-action listener: set_extra_try_arguments 18:09:49 INFO - ##### 18:09:49 INFO - ##### Finished download-and-extract step (success) 18:09:49 INFO - ##### 18:09:49 INFO - ##### 18:09:49 INFO - ##### Running populate-webroot step. 18:09:49 INFO - ##### 18:09:49 INFO - Running pre-action listener: _resource_record_pre_action 18:09:49 INFO - Running main action method: populate_webroot 18:09:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:09:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:09:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:09:49 INFO - '--tppagecycles', 18:09:49 INFO - '7'], 18:09:49 INFO - 'tests': ['tsvgm']}}, 18:09:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:09:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:09:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:09:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:09:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:09:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:09:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['damp', 'tps']}, 18:09:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'tests': ['damp', 'tps']}, 18:09:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['dromaeo_dom']}, 18:09:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:09:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['a11yr', 18:09:49 INFO - 'ts_paint', 18:09:49 INFO - 'tpaint', 18:09:49 INFO - 'sessionrestore', 18:09:49 INFO - 'sessionrestore_no_auto_restore', 18:09:49 INFO - 'tabpaint']}, 18:09:49 INFO - 'other-e10s': {'tests': ['a11yr', 18:09:49 INFO - 'ts_paint', 18:09:49 INFO - 'tpaint', 18:09:49 INFO - 'sessionrestore', 18:09:49 INFO - 'sessionrestore_no_auto_restore', 18:09:49 INFO - 'tabpaint']}, 18:09:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:09:49 INFO - 'ts_paint', 18:09:49 INFO - 'tpaint', 18:09:49 INFO - 'sessionrestore', 18:09:49 INFO - 'sessionrestore_no_auto_restore', 18:09:49 INFO - 'tabpaint']}, 18:09:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:09:49 INFO - 'ts_paint', 18:09:49 INFO - 'tpaint', 18:09:49 INFO - 'sessionrestore', 18:09:49 INFO - 'sessionrestore_no_auto_restore', 18:09:49 INFO - 'tabpaint']}, 18:09:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['a11yr', 18:09:49 INFO - 'ts_paint', 18:09:49 INFO - 'tpaint', 18:09:49 INFO - 'sessionrestore', 18:09:49 INFO - 'sessionrestore_no_auto_restore', 18:09:49 INFO - 'tabpaint']}, 18:09:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['a11yr', 18:09:49 INFO - 'ts_paint', 18:09:49 INFO - 'tpaint', 18:09:49 INFO - 'sessionrestore', 18:09:49 INFO - 'sessionrestore_no_auto_restore', 18:09:49 INFO - 'tabpaint']}, 18:09:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['tsvgx', 18:09:49 INFO - 'tsvgr_opacity', 18:09:49 INFO - 'tart', 18:09:49 INFO - 'tscrollx', 18:09:49 INFO - 'cart']}, 18:09:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:09:49 INFO - 'tsvgr_opacity', 18:09:49 INFO - 'tart', 18:09:49 INFO - 'tscrollx', 18:09:49 INFO - 'cart']}, 18:09:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'talos_options': ['--disable-e10s'], 18:09:49 INFO - 'tests': ['tp5o']}, 18:09:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'tests': ['tp5o']}, 18:09:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'talos_options': ['--disable-e10s', 18:09:49 INFO - '--xperf_path', 18:09:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:09:49 INFO - 'tests': ['tp5n']}, 18:09:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:09:49 INFO - 'talos_options': ['--xperf_path', 18:09:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:09:49 INFO - 'tests': ['tp5n']}}, 18:09:49 INFO - 'talos.zip': {'path': '', 18:09:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:09:49 INFO - Running post-action listener: _resource_record_post_action 18:09:49 INFO - ##### 18:09:49 INFO - ##### Finished populate-webroot step (success) 18:09:49 INFO - ##### 18:09:49 INFO - ##### 18:09:49 INFO - ##### Running create-virtualenv step. 18:09:49 INFO - ##### 18:09:49 INFO - Running pre-action listener: _resource_record_pre_action 18:09:49 INFO - Running main action method: create_virtualenv 18:09:49 INFO - Creating virtualenv /builds/slave/test/build/venv 18:09:49 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 18:09:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:09:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:09:49 INFO - Using real prefix '/tools/python27' 18:09:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:09:50 INFO - Installing distribute.............................................................................................................................................................................................done. 18:09:52 INFO - Installing pip.................done. 18:09:52 INFO - Return code: 0 18:09:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:09:52 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')]} 18:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:52 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')]} 18:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:52 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:09:52 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 18:09:52 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 18:09:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:09:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:52 INFO - 'HOME': '/Users/cltbld', 18:09:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:52 INFO - 'LOGNAME': 'cltbld', 18:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:52 INFO - 'MOZ_NO_REMOTE': '1', 18:09:52 INFO - 'NO_EM_RESTART': '1', 18:09:52 INFO - 'PAGER': '/bin/cat', 18:09:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:52 INFO - 'PWD': '/builds/slave/test', 18:09:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:52 INFO - 'SHELL': '/bin/bash', 18:09:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:09:52 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:09:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:52 INFO - 'USER': 'cltbld', 18:09:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:52 INFO - 'XPC_FLAGS': '0x0', 18:09:52 INFO - 'XPC_SERVICE_NAME': '0', 18:09:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:53 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 18:09:53 INFO - Cleaning up... 18:09:53 INFO - Return code: 0 18:09:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:09:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:53 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:09:53 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 18:09:53 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 18:09:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:09:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:53 INFO - 'HOME': '/Users/cltbld', 18:09:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:53 INFO - 'LOGNAME': 'cltbld', 18:09:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:53 INFO - 'MOZ_NO_REMOTE': '1', 18:09:53 INFO - 'NO_EM_RESTART': '1', 18:09:53 INFO - 'PAGER': '/bin/cat', 18:09:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:53 INFO - 'PWD': '/builds/slave/test', 18:09:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:53 INFO - 'SHELL': '/bin/bash', 18:09:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:09:53 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:09:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:53 INFO - 'USER': 'cltbld', 18:09:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:53 INFO - 'XPC_FLAGS': '0x0', 18:09:53 INFO - 'XPC_SERVICE_NAME': '0', 18:09:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:53 INFO - Downloading/unpacking psutil>=3.1.1 18:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:09:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:56 INFO - Installing collected packages: psutil 18:09:56 INFO - Running setup.py install for psutil 18:09:56 INFO - building 'psutil._psutil_osx' extension 18:09:56 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 18:09:57 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 18:09:57 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 18:09:57 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 18:09:57 INFO - building 'psutil._psutil_posix' extension 18:09:57 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 18:09:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:09:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:09:57 INFO - ^ 18:09:57 INFO - 1 warning generated. 18:09:57 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 18:09:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:09:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:09:58 INFO - Successfully installed psutil 18:09:58 INFO - Cleaning up... 18:09:58 INFO - Return code: 0 18:09:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:09:58 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')]} 18:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:58 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')]} 18:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:09:58 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:09:58 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 18:09:58 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 18:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:58 INFO - 'HOME': '/Users/cltbld', 18:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:58 INFO - 'LOGNAME': 'cltbld', 18:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:58 INFO - 'MOZ_NO_REMOTE': '1', 18:09:58 INFO - 'NO_EM_RESTART': '1', 18:09:58 INFO - 'PAGER': '/bin/cat', 18:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:58 INFO - 'PWD': '/builds/slave/test', 18:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:58 INFO - 'SHELL': '/bin/bash', 18:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:09:58 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:58 INFO - 'USER': 'cltbld', 18:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:58 INFO - 'XPC_FLAGS': '0x0', 18:09:58 INFO - 'XPC_SERVICE_NAME': '0', 18:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:09:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:09:58 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 18:09:58 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 18:09:58 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 18:09:58 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 18:09:58 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 18:09:58 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 18:10:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:10:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:10:00 INFO - Installing collected packages: mozsystemmonitor 18:10:00 INFO - Running setup.py install for mozsystemmonitor 18:10:01 INFO - Successfully installed mozsystemmonitor 18:10:01 INFO - Cleaning up... 18:10:01 INFO - Return code: 0 18:10:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:10:01 INFO - 'HOME': '/Users/cltbld', 18:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:10:01 INFO - 'LOGNAME': 'cltbld', 18:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:01 INFO - 'MOZ_NO_REMOTE': '1', 18:10:01 INFO - 'NO_EM_RESTART': '1', 18:10:01 INFO - 'PAGER': '/bin/cat', 18:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:01 INFO - 'PWD': '/builds/slave/test', 18:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:10:01 INFO - 'SHELL': '/bin/bash', 18:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:10:01 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:10:01 INFO - 'USER': 'cltbld', 18:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:01 INFO - 'XPC_FLAGS': '0x0', 18:10:01 INFO - 'XPC_SERVICE_NAME': '0', 18:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:01 INFO - Downloading/unpacking blobuploader==1.2.4 18:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:03 INFO - Downloading blobuploader-1.2.4.tar.gz 18:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:10:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:10:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:10:04 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 18:10:04 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 18:10:04 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 18:10:04 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 18:10:04 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 18:10:04 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 18:10:04 INFO - Downloading docopt-0.6.1.tar.gz 18:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:10:05 INFO - Installing collected packages: blobuploader, requests, docopt 18:10:05 INFO - Running setup.py install for blobuploader 18:10:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:10:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:10:05 INFO - Running setup.py install for requests 18:10:05 INFO - Running setup.py install for docopt 18:10:05 INFO - Successfully installed blobuploader requests docopt 18:10:05 INFO - Cleaning up... 18:10:05 INFO - Return code: 0 18:10:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:05 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:10:05 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 18:10:05 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 18:10:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:10:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:10:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:10:05 INFO - 'HOME': '/Users/cltbld', 18:10:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:10:05 INFO - 'LOGNAME': 'cltbld', 18:10:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:05 INFO - 'MOZ_NO_REMOTE': '1', 18:10:05 INFO - 'NO_EM_RESTART': '1', 18:10:05 INFO - 'PAGER': '/bin/cat', 18:10:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:10:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:05 INFO - 'PWD': '/builds/slave/test', 18:10:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:10:05 INFO - 'SHELL': '/bin/bash', 18:10:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:10:05 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:10:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:10:05 INFO - 'USER': 'cltbld', 18:10:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:10:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:10:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:05 INFO - 'XPC_FLAGS': '0x0', 18:10:05 INFO - 'XPC_SERVICE_NAME': '0', 18:10:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-rlagGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-YhWEZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-3PvbVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-DlolAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-kB27MQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-0GOLXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-LIAmGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:10:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-87IukL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-ExlUkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-dCypLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-ORHM2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-E847J9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-W4ZBNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-_F0bTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-BtbXUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-qi0rGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:10:07 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-VUROFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:10:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:10:07 INFO - Running setup.py install for manifestparser 18:10:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:10:08 INFO - Running setup.py install for mozcrash 18:10:08 INFO - Running setup.py install for mozdebug 18:10:08 INFO - Running setup.py install for mozdevice 18:10:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:10:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:10:08 INFO - Running setup.py install for mozfile 18:10:08 INFO - Running setup.py install for mozhttpd 18:10:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:10:08 INFO - Running setup.py install for mozinfo 18:10:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Running setup.py install for mozInstall 18:10:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Running setup.py install for mozleak 18:10:09 INFO - Running setup.py install for mozlog 18:10:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Running setup.py install for moznetwork 18:10:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Running setup.py install for mozprocess 18:10:09 INFO - Running setup.py install for mozprofile 18:10:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:10:09 INFO - Running setup.py install for mozrunner 18:10:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:10:10 INFO - Running setup.py install for mozscreenshot 18:10:10 INFO - Running setup.py install for moztest 18:10:10 INFO - Running setup.py install for mozversion 18:10:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:10:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:10:10 INFO - Cleaning up... 18:10:10 INFO - Return code: 0 18:10:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:10 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')]} 18:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:10 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')]} 18:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:10 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:10:10 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 18:10:10 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 18:10:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:10:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:10:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:10:10 INFO - 'HOME': '/Users/cltbld', 18:10:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:10:10 INFO - 'LOGNAME': 'cltbld', 18:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:10 INFO - 'MOZ_NO_REMOTE': '1', 18:10:10 INFO - 'NO_EM_RESTART': '1', 18:10:10 INFO - 'PAGER': '/bin/cat', 18:10:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:10 INFO - 'PWD': '/builds/slave/test', 18:10:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:10:10 INFO - 'SHELL': '/bin/bash', 18:10:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:10:10 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:10:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:10:10 INFO - 'USER': 'cltbld', 18:10:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:10:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:10 INFO - 'XPC_FLAGS': '0x0', 18:10:10 INFO - 'XPC_SERVICE_NAME': '0', 18:10:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:10:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-U_EDdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:10:10 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)) 18:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:10:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-BNGNN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-PGu6iF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-QU1e41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-_tOtV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-B363Fo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-YUhuC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-wXPb5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-RSF3Mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-z7F7O0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:10:11 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)) 18:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:10:11 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-sUy2Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:10:12 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)) 18:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:10:12 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-S99yXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:10:12 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)) 18:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:10:12 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-YdXiM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:10:12 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)) 18:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:10:12 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-6qsxJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:10:12 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)) 18:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:12 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-a3E0wZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:10:12 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)) 18:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:10:12 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-kihz6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:10:12 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)) 18:10:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:10:12 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-_fUKan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:10:12 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)) 18:10:12 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)) 18:10:12 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)) 18:10:12 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)) 18:10:12 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)) 18:10:12 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)) 18:10:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:10:12 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 18:10:12 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 18:10:12 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 18:10:12 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 18:10:12 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 18:10:12 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 18:10:15 INFO - Downloading blessings-1.6.tar.gz 18:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:10:15 INFO - Installing collected packages: blessings 18:10:15 INFO - Running setup.py install for blessings 18:10:15 INFO - Successfully installed blessings 18:10:15 INFO - Cleaning up... 18:10:15 INFO - Return code: 0 18:10:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:10:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:15 INFO - Reading from file tmpfile_stdout 18:10:15 INFO - Current package versions: 18:10:15 INFO - blessings == 1.6 18:10:15 INFO - blobuploader == 1.2.4 18:10:15 INFO - docopt == 0.6.1 18:10:15 INFO - manifestparser == 1.1 18:10:15 INFO - mozInstall == 1.12 18:10:15 INFO - mozcrash == 0.17 18:10:15 INFO - mozdebug == 0.1 18:10:15 INFO - mozdevice == 0.48 18:10:15 INFO - mozfile == 1.2 18:10:15 INFO - mozhttpd == 0.7 18:10:15 INFO - mozinfo == 0.9 18:10:15 INFO - mozleak == 0.1 18:10:15 INFO - mozlog == 3.2 18:10:15 INFO - moznetwork == 0.27 18:10:15 INFO - mozprocess == 0.22 18:10:15 INFO - mozprofile == 0.28 18:10:15 INFO - mozrunner == 6.11 18:10:15 INFO - mozscreenshot == 0.1 18:10:15 INFO - mozsystemmonitor == 0.3 18:10:15 INFO - moztest == 0.7 18:10:15 INFO - mozversion == 1.4 18:10:15 INFO - psutil == 3.1.1 18:10:15 INFO - requests == 1.2.3 18:10:15 INFO - wsgiref == 0.1.2 18:10:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:10:15 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')]} 18:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:15 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')]} 18:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:15 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:10:15 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 18:10:15 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 18:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:10:15 INFO - 'HOME': '/Users/cltbld', 18:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:10:15 INFO - 'LOGNAME': 'cltbld', 18:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:15 INFO - 'MOZ_NO_REMOTE': '1', 18:10:15 INFO - 'NO_EM_RESTART': '1', 18:10:15 INFO - 'PAGER': '/bin/cat', 18:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:15 INFO - 'PWD': '/builds/slave/test', 18:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:10:15 INFO - 'SHELL': '/bin/bash', 18:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:10:15 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:10:15 INFO - 'USER': 'cltbld', 18:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:15 INFO - 'XPC_FLAGS': '0x0', 18:10:15 INFO - 'XPC_SERVICE_NAME': '0', 18:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 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)) 18:10:16 INFO - Cleaning up... 18:10:16 INFO - Return code: 0 18:10:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:10:16 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')]} 18:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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')]} 18:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:16 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:10:16 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 18:10:16 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 18:10:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:10:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:10:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:10:16 INFO - 'HOME': '/Users/cltbld', 18:10:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:10:16 INFO - 'LOGNAME': 'cltbld', 18:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:16 INFO - 'MOZ_NO_REMOTE': '1', 18:10:16 INFO - 'NO_EM_RESTART': '1', 18:10:16 INFO - 'PAGER': '/bin/cat', 18:10:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:16 INFO - 'PWD': '/builds/slave/test', 18:10:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:10:16 INFO - 'SHELL': '/bin/bash', 18:10:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:10:16 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:10:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:10:16 INFO - 'USER': 'cltbld', 18:10:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:10:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:16 INFO - 'XPC_FLAGS': '0x0', 18:10:16 INFO - 'XPC_SERVICE_NAME': '0', 18:10:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:16 INFO - Downloading/unpacking jsonschema 18:10:16 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 18:10:16 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 18:10:16 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 18:10:16 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 18:10:16 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 18:10:16 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 18:10:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:10:18 INFO - Downloading/unpacking functools32 (from jsonschema) 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10: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 18:10:19 INFO - Downloading functools32-3.2.3-2.tar.gz 18:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:10:19 INFO - warning: no files found matching '*.txt' 18:10:19 INFO - no previously-included directories found matching 'build' 18:10:19 INFO - no previously-included directories found matching 'dist' 18:10:19 INFO - no previously-included directories found matching '.git*' 18:10:19 INFO - Installing collected packages: jsonschema, functools32 18:10:19 INFO - Running setup.py install for functools32 18:10:19 INFO - warning: no files found matching '*.txt' 18:10:19 INFO - no previously-included directories found matching 'build' 18:10:19 INFO - no previously-included directories found matching 'dist' 18:10:19 INFO - no previously-included directories found matching '.git*' 18:10:19 INFO - Successfully installed jsonschema functools32 18:10:19 INFO - Cleaning up... 18:10:19 INFO - Return code: 0 18:10:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:10:19 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 0x10499ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049a1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb250d9d100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, '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.HAeEojBL0a/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.R62gLrl6cs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 18:10:19 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 18:10:19 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 18:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:10:19 INFO - 'HOME': '/Users/cltbld', 18:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:10:19 INFO - 'LOGNAME': 'cltbld', 18:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:10:19 INFO - 'MOZ_NO_REMOTE': '1', 18:10:19 INFO - 'NO_EM_RESTART': '1', 18:10:19 INFO - 'PAGER': '/bin/cat', 18:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:10:19 INFO - 'PWD': '/builds/slave/test', 18:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:10:19 INFO - 'SHELL': '/bin/bash', 18:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:10:19 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:10:19 INFO - 'USER': 'cltbld', 18:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:10:19 INFO - 'XPC_FLAGS': '0x0', 18:10:19 INFO - 'XPC_SERVICE_NAME': '0', 18:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:10:19 INFO - Downloading/unpacking flake8 18:10:19 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 18:10:19 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 18:10:19 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 18:10:19 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 18:10:19 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 18:10:19 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 18:10:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:10:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:10:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:10:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:10:23 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 18:10:23 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 18:10:23 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 18:10:23 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 18:10:23 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 18:10:23 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 18:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:10:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:10:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:10:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:10:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:10:23 INFO - no previously-included directories found matching 'docs/_build' 18:10:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:10:23 INFO - Running setup.py install for pep8 18:10:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:10:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:10:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:10:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:10:23 INFO - no previously-included directories found matching 'docs/_build' 18:10:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:10:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:10:23 INFO - Cleaning up... 18:10:23 INFO - Return code: 0 18:10:23 INFO - Running post-action listener: _resource_record_post_action 18:10:23 INFO - Running post-action listener: _start_resource_monitoring 18:10:23 INFO - Starting resource monitoring. 18:10:23 INFO - ##### 18:10:23 INFO - ##### Finished create-virtualenv step (success) 18:10:23 INFO - ##### 18:10:23 INFO - ##### 18:10:23 INFO - ##### Running install step. 18:10:23 INFO - ##### 18:10:23 INFO - Running pre-action listener: _resource_record_pre_action 18:10:23 INFO - Running main action method: install 18:10:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:10:24 INFO - Reading from file tmpfile_stdout 18:10:24 INFO - Detecting whether we're running mozinstall >=1.0... 18:10:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:10:24 INFO - Reading from file tmpfile_stdout 18:10:24 INFO - Output received: 18:10:24 INFO - Usage: mozinstall [options] installer 18:10:24 INFO - Options: 18:10:24 INFO - -h, --help show this help message and exit 18:10:24 INFO - -d DEST, --destination=DEST 18:10:24 INFO - Directory to install application into. [default: 18:10:24 INFO - "/builds/slave/test"] 18:10:24 INFO - --app=APP Application being installed. [default: firefox] 18:10:24 INFO - mkdir: /builds/slave/test/build/application 18:10:24 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'] 18:10:24 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 18:10:59 INFO - Reading from file tmpfile_stdout 18:10:59 INFO - Output received: 18:10:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:10:59 INFO - Running post-action listener: _resource_record_post_action 18:10:59 INFO - ##### 18:10:59 INFO - ##### Finished install step (success) 18:10:59 INFO - ##### 18:10:59 INFO - ##### 18:10:59 INFO - ##### Running run-tests step. 18:10:59 INFO - ##### 18:10:59 INFO - Running pre-action listener: _resource_record_pre_action 18:10:59 INFO - Running main action method: run_tests 18:10:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:10:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:10:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:10:59 INFO - 2016-05-27 18:10:59.504 screenresolution[1642:7711] starting screenresolution argv=screenresolution get 18:10:59 INFO - 2016-05-27 18:10:59.507 screenresolution[1642:7711] Display 0: 1600x1200x32@60 18:10:59 INFO - 2016-05-27 18:10:59.513 screenresolution[1643:7713] starting screenresolution argv=screenresolution list 18:10:59 INFO - Available Modes on Display 0 18:10:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:10:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:10:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:10:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:10:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:10:59 INFO - Intel Iris: 18:10:59 INFO - Chipset Model: Intel Iris 18:10:59 INFO - Type: GPU 18:10:59 INFO - Bus: Built-In 18:10:59 INFO - VRAM (Dynamic, Max): 1536 MB 18:10:59 INFO - Vendor: Intel (0x8086) 18:10:59 INFO - Device ID: 0x0a2e 18:10:59 INFO - Revision ID: 0x0009 18:10:59 INFO - Displays: 18:10:59 INFO - Display: 18:10:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:10:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:10:59 INFO - Display Serial Number: 1600x1200 60 18:10:59 INFO - Main Display: Yes 18:10:59 INFO - Mirror: Off 18:10:59 INFO - Online: Yes 18:10:59 INFO - Rotation: Supported 18:10:59 INFO - Return code: 0 18:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:10:59 INFO - Python 2.7.3 18:10:59 INFO - Return code: 0 18:10:59 INFO - grabbing minidump binary from tooltool 18:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:10:59 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 0x10483fd30>, '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 0x104663db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104836b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:10:59 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 18:10:59 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 18:10:59 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 18:10:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:10:59 INFO - Return code: 0 18:10:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:10:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:10:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:10:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:10:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:11:00 INFO - Return code: 0 18:11:00 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/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 18:11:00 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/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0177 --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 18:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HAeEojBL0a/Render', 18:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:00 INFO - 'HOME': '/Users/cltbld', 18:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:00 INFO - 'LOGNAME': 'cltbld', 18:11:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:11:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:00 INFO - 'MOZ_NO_REMOTE': '1', 18:11:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:11:00 INFO - 'NO_EM_RESTART': '1', 18:11:00 INFO - 'PAGER': '/bin/cat', 18:11:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:00 INFO - 'PWD': '/builds/slave/test', 18:11:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:00 INFO - 'SHELL': '/bin/bash', 18:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.R62gLrl6cs/Listeners', 18:11:00 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 18:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:00 INFO - 'USER': 'cltbld', 18:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:00 INFO - 'XPC_FLAGS': '0x0', 18:11:00 INFO - 'XPC_SERVICE_NAME': '0', 18:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:00 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/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 18:11:01 INFO - mozversion application_buildid: 20160527161740 18:11:01 INFO - mozversion application_changeset: 1bd815acf6d3d51ded73a0ffbe05bd8a2c515784 18:11:01 INFO - mozversion application_display_name: Nightly 18:11:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:11:01 INFO - mozversion application_name: Firefox 18:11:01 INFO - mozversion application_remotingname: firefox 18:11:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:11:01 INFO - mozversion application_vendor: Mozilla 18:11:01 INFO - mozversion application_version: 49.0a1 18:11:01 INFO - mozversion platform_buildid: 20160527161740 18:11:01 INFO - mozversion platform_changeset: 1bd815acf6d3d51ded73a0ffbe05bd8a2c515784 18:11:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:11:01 INFO - mozversion platform_version: 49.0a1 18:11:01 INFO - using testdate: 1464397861 18:11:01 INFO - actual date: 1464397861 18:11:01 INFO - starting webserver on 'localhost:49271' 18:11:01 INFO - SUITE-START | Running 2 tests 18:11:01 INFO - TEST-START | dromaeo_css 18:11:01 INFO - operating with platform_type : mac_ 18:11:01 INFO - Initialising browser for dromaeo_css test... 18:11:01 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpB8sVp8/profile http://localhost:49271/getInfo.html) 18:11:03 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 18:11:03 INFO - PROCESS | 1661 | colorDepth:24 18:11:03 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 18:11:03 INFO - PROCESS | 1661 | __metrics 18:11:03 INFO - PROCESS | 1661 | 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:11:03 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:11:04 INFO - TEST-INFO | 1661: exit 0 18:11:04 INFO - Browser initialized. 18:11:04 INFO - Running cycle 1/1 for dromaeo_css test... 18:11:04 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpB8sVp8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:11:16 INFO - PROCESS | 1664 | RSS: Main: 241881088 18:11:16 INFO - PROCESS | 1664 | 18:12:07 INFO - PROCESS | 1664 | 1464397927238 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] 18:14:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 18:14:20 INFO - PROCESS | 1664 | RSS: Main: 386727936 18:14:20 INFO - PROCESS | 1664 | 18:17:23 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 18:17:24 INFO - PROCESS | 1664 | RSS: Main: 378744832 18:17:24 INFO - PROCESS | 1664 | 18:20:26 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 18:20:27 INFO - PROCESS | 1664 | RSS: Main: 370921472 18:20:27 INFO - PROCESS | 1664 | 18:23:29 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 18:23:30 INFO - PROCESS | 1664 | RSS: Main: 368566272 18:23:30 INFO - PROCESS | 1664 | 18:26:33 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 18:26:34 INFO - PROCESS | 1664 | RSS: Main: 335261696 18:26:34 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 18:29:37 INFO - PROCESS | 1664 | RSS: Main: 393076736 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | __start_tp_report 18:29:37 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 18:29:37 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 18:29:37 INFO - PROCESS | 1664 | |i|pagename|runs| 18:29:37 INFO - PROCESS | 1664 | |0;dojo.html;3430.5368775215343;3446.707029902458;3466.7399945004117;3475.843587038583;3526.735494837886;5568.582356323277;5589.469000444951;5656.2929633795775;5722.198892155091;5723.255976723025;5586.776528938848;5651.745671444784;5651.943480565194;5688.573356998221;5695.117256825199;5049.091163590552;5098.107831129556;5138.126518491866;5162.199859021839;5262.789488420459;5271.630985830993;5279.2872962150195;5285.312909321802;5324.467553244684;5361.847202851386;5125.179971204627;5162.22566615007;5173.974130129325;5207.895842083156;5229.869253268661;5211.791528338862;5223.608229382811;5223.869403264911;5225.16397376418;5230.92153617696;5128.5640720538795;5160.045391602565;5182.766775495112;5192.974035129838;5216.087184742692;5581.944180558224;5636.718164091779;5642.605017648766;5655.236543066674;5710.972025035492;6101.017169419956;6147.969260153715;6170.691465426711;6218.378162183791;6229.09678096678;6240.875182496325;6254.186955695731;6282.623042617412;6292.339304373067;6297.149884765544;5706.229658996065;5745.85635359111;5746.712664366765;5812.244408226988;5859.970700146472;6217.813465596039;6250.406211409909;6262.467690246289;6263.843403914939;6265.028920517327;6146.508279337731;6179.5056395488255;6189.597676151036;6190.628562286277;6192.442680158807;6109.95128740435;6161.199044124235;6229.470495007975;6266.749330026839;6276.560640755103;6224.626522408579;6236.469900058546;6246.031865060922;6249.6875156242;6252.937470625236;6176.629402235881;6239.719212635442;6249.1563638908165;6265.154204182377;6265.467435267962;4841.612670986313;4898.657094003457;4900.5099490051425;4943.085529177114;5008.974955125201;4466.129104824528;4475.888102797391;4488.551144885485;4513.029698614813;4514.142312960527;5632.070708333161;5645.971770141123;5672.035753921843;5674.801381951695;5683.2895888014;6197.752089916443;6285.9057114143325;6295.968520157461;6298.401651843067;6300;5697.743601537941;5710.828675139753;5712.171735098387;5719;5728.341240757366;5289.523942845162;5345.492178243061;5366.463353664681;5368.892622147535;5389.51494365509;6228.439440450381;6349.920513512714;6353;6355.3644635537;6368.840778980562;6270.592411493239;6280.623162610258;6294.8111556653375;6300.684965751602;6307.337729538516;6174.320824709368;6204.81385558434;6229.470495007975;6337.4613157882095;6355.396237357444;6197.96901015492;6198.81403557894;6207.534434917489;6220.222472190976;6233.345498722751;6241.812745617639;6245.437910588069;6252.5935814171935;6253.437190652863;6259.96870015647;5922.822315330547;5971.104334349882;5986.820395388146;6033.306169790355;6119.388061193845;6057.878842423039;6103.176071230327;6105.45050945417;6130.7241174146375;6141.048137538689;5938.2280303560265;5981.52147828164;5982.940170598238;6042.728077236447;6052.092186172109;5858;5860.150278209721;6132.6627035513475;6140.3552626975315;6147.692615369303;5886.470217680429;5911.733971971357;5952.851178720567;5974.283086029531;6002.979493486205 18:29:37 INFO - PROCESS | 1664 | |1;ext.html;524.8058218459166;525.2777431032329;528.6616565398144;532.2123257578783;533.7838175538906;27753.583696244546;40891.59108408919;43654.78172609132;43695.12609747811;43723.56276437235;45143.54856451443;45538.861528461806;45742.56930730192;45855.54144458554;45900.770496147474;8492.065872753992;9383.859242111357;9603.519824008807;9612.327137100407;9634.807303853919;8586.87119693205;8651.178138076873;8663.870041949376;8669.393142480028;11031.448427578629;11373.601923932663;11605.245659032178;11613.18707690462;11629.127815413836;11630.592929247478;9945.303828731992;11632.953034226917;11736.354500502512;11742.70643233926;11745.354005529693;8515.233628973423;8746.300295976307;8764.693235736782;8853.69012084574;11516;35807.4628880567;40032;40671.59328406737;42863.35704964428;43103;3461.7230621550216;3473.114355839245;3613.9096522586883;3620.7827530348263;3853.8651147209994;25707.74292257072;31765;32010;32125.83937080323;33017.50473742896;3222.0978126124487;3266.055956902753;3297.5383446317296;3313.072339744875;3436.2096717754807;445128;449362;450124;450687;453607.4639253598;62245.06632400517;62538.68730656317;62608.686956564925;62714;62769;66127;66147.66926165434;66203.33796662067;66213.33786662071;66258.0183891726;16371.754423683655;16955.660886782443;16959.660806783795;16974.745378819327;16996.660066798842;12971.481140754264;13019.088663793444;13032.087753857139;13036.869631303754;13050.54323098687;15518.68091212237;15910.76133857993;17744.733828992576;18013.549661258574;18157.27370905149;5860.472557469848;6182.474489668339;6209.19280493542;6228.159198508143;6248;960.5677445149711;964.625408792468;1039.6101461951769;1116.6594188772503;1154.1805318223987;30683.539746903814;30700.69299307023;30714;31421.685783142333;31931.84034079861;1132.201797732613;1177.8351030855688;1197.7903866823272;1209.7399059202314;1234.6789834642877;15878;16020.75968860468;16100.516984490485;16149.757753633705;16481.6703665926;653773;658376;658621;659548;660662;58586.70706646525;59874;60023.09965350523;60051;60152;1259.1563652352763;1283.9935800321125;1402.7475054490085;1430.7782293744485;1447.3848614339074;30594.694053059186;32004;32474.512882306313;32780.836095819366;32821;54230;54989;55132.17301740557;55149.72425137849;55422.168667470825;513288;549552;567237;572347;591600.5879588388;32114.357712845143;37525.249495010496;47048.059038819716;48403.91758865269;48629.75685121552;42946.00944822215;47924;49302.506974931224;49463.01073978572;50665.74667126641;39730.40404393878;40043.998900027735;40250;40367.596324036385;41143.7942810284;34431.827840860635;39917.800410997756;40557.797211013756;40592.1881562373;41117.97205069897;38397.808010960885;39044.6095539041;39905.800470997456;39989.000274993356;40047.599524004385;30522.389552209275;33410.16474588155;34232.486512701835;35139.82430087833;35167.64832351746;21274.680879786505;25730.970761169345;25902.481950361263;26276.21171364862;26478 18:29:37 INFO - PROCESS | 1664 | |2;jquery.html;378.9374753165728;379.1412450798939;380.0896852039366;391.42656008946886;396.142351808335;15632.921835390822;19957.501062473428;20676;20678.89660551695;20843;20167.09248083836;20427.285045023425;20509.794902050977;20897.477563060933;20993.47516312093;4346.565343465644;5783.161441590967;6011.669358185298;6059.909101363483;6062.51468390245;4084.101497670508;4351.238533256684;6269.153664255336;6599.703013364386;6608.702608382634;6496.090547323355;6575;6584.176977877765;6589.93410065898;6612.338766123373;5952.226210592596;6081.969590152065;6556.08214849919;6564.442022428076;6567.770128045543;3712.6472985066644;5386.542143917772;5726.141078838166;5734.111212762028;5778.8266352009505;25004.49991000188;25066.498670026496;25614.87192564044;25733.871330643226;25906.481870362677;2063.876167429944;2199.967000494994;2219.8557093789013;2466.0382450844186;3021.123874076515;10355.585776569003;10419.374837509698;10423.583056677724;10442.68671939843;14237.93215508841;1819.0359109671895;1841.24161425838;1902.9429117126508;1919.3474218765548;2006.9698954515693;121776;128166.99999999907;129354;130086;131831;11622.418879056013;11650.825237621442;11679.065674746;11699.356535390636;11842.230255033395;15004.549863503895;15061.322240499203;15072.924635376752;15091.01908375974;15091.396344146331;7173.318534739192;8411.242988131098;8745.851493462349;8833.249173820297;8883.022867484571;7027.332403421667;8551.102134275936;8568.785780355416;8611.440256383315;8703.695370661997;4157.376393540932;4495.370648109268;4600.930986035212;4609.216433206363;4676.579107880307;1655.0566177278836;1764.6382491589193;1933.1011079847938;1947.8733882297606;1954.3355259211937;919.6689191890914;928.4939707859232;948.5684013773716;956.8134213828237;987.8765154355706;19404.2238310466;20331.593368132555;20410.69383959242;20530.589388212153;20735.8963205183;904.0688091265876;984.8424252119627;996.5714742660581;1015.4415071710678;1058.0688993685508;8844.55777211137;11152.275102118336;11162.386068766296;11185.49665265065;11225.887741122648;540101;571885;581678.683307641;582339;582588;123537;124630.37684811518;127005;131492;135836;980.1521683743653;1002.7242508310273;1025.4462590201208;1026.4662375564665;1028.665683652816;17699.380521682193;18581;18747;18795.06024698787;18833.869967801977;38562.614373855904;54429.72785136049;55357.72321138369;56321.43678563162;56338;76944;81208;81797.18202817896;82048;82082;8154.592270386338;8193.877091843746;8216.753497395088;8295.460795048302;8404.369672274726;10338.328008679411;11255.649322081403;12336.383180841101;12571.622851314476;13538.86461135376;10753.1935104869;11459.140564457866;11623.65129046096;11701.590444334408;11766.529338826693;9403.341766076583;11129.833052504056;11366.147538934447;11507.309561426342;11735.589254376056;11678.6496405108;11793.882061179278;11936.462859171184;11937.28376297425;11940.641780746591;8235.258826705385;8372.162783721578;8418.158184181533;8536.274416674652;8569.400141990001;9964.950175249078;10215.387076775418;10430.217733669853;10555.89444105549;10641.840372394265 18:29:37 INFO - PROCESS | 1664 | |3;mootools.html;1397.2245403800898;1404.7822587498938;1528.9541313760587;1739.4086010756341;1927.9325223617168;7826.295633392993;8370.330373570116;8638.524881131536;8662.263707584847;8683.478991260528;8233.79415514611;8545.572721363938;8567.228949394554;8682.218600325969;8692.47845129291;2347.7182738071456;2398.556267090585;2402.327348342458;2403.759624037591;2405.9759402406016;2359.4101474631343;2391.796697280733;2403.098837935774;2404.302752201859;2411.9035238590436;2368.7394386617457;2389.426537630965;2397.9520409591796;2406.362313986795;2406.7713567211176;2350.7766762157567;2354.2937118864443;2383.2611890314056;2392.091005417939;2392.9042838286446;2326.5579539887367;2389.784919357266;2416.6949847082556;2417.9602770808547;2425.5391475619576;7697.653605587762;7929.444938854283;8714;8770.473771573726;8797.252233560155;4876.341693871317;5053.974730126326;5053.974730126362;5160;5385.434529374437;3533.0990597397754;3609.314230296235;3652.6712595866234;3674.0631139059637;3677.8712745054063;893.3433926064338;1014.852846337277;1061.1139698351885;1107.429673718036;1109.4286442482128;11388.430578471127;11616.244944078608;11633.6509904703;11635.360055196961;11636.767264654743;4474.306482495218;4715.339852420664;4901.50984901507;4912.631552633567;4940.209566469348;9924.503774811375;9945.20438364929;9962.203023758082;9972.30193886421;9973;5412.91880621791;5414.214938833847;5422.295101636841;5423;5427.511523962783;4745.454272758608;4961.875953101129;4965.702057876539;4984.077945580079;4986.700797952062;4027.6989886739375;4035.7982100894837;4076.2051399977313;4134.718237346432;4187.811548480326;1096.2600244834864;1111.4498323330004;1282.1153404151291;1301.6745813546613;1311.2263764379063;666.5334266013809;815.2703330519153;855.7561095087871;941.1858742188095;997.1823105053932;5844.298684157929;5851.1223316502865;5938.495227905676;5944.227250457413;6076.4531192192035;946.4557879219476;947.7488465556632;960.582146766159;970.2141265574907;1007.3502590829021;3903.394973779069;3948.2498325318097;3982.661473774762;4013.6588389986596;4015.3374693175883;9834.704958851245;10443.216758743125;10450.843237351446;10451.111655509214;10487.056164945192;10071.647492337734;10242.078212960869;10401.959804019538;10412.531436085394;10414.062734353945;8102.7569172924905;8154.3884208684585;8227.588620568948;8244.010718713673;8275.172482751797;5659.207710920557;5683.8010669626365;5778.8844223114465;5795.246617939641;5851.444112809277;9915.405075695482;10027.197824173903;10036.64871729486;10042.849357259793;10061.245406594388;9957.950210248902;9987.151092157248;10017.198624109908;10261.435621040915;10277.897221027994;6990.580565166107;7025.297470252934;7095.929040709527;7120.181179164255;7123.252058533986;7798.181190974866;8183;8254.587270636564;8268.710595129141;8271.131531189138;7364.3003914628025;7424.220456851953;7458.47790654649;7589.772306830804;7618.69525218985;7029.437644988287;7299.124105107208;7399.371053460516;7531.962340188264;7548.018757561482;6925.238223795479;7079.610621415872;7083.716651333889;7094.255103214088;7097.574145551283;4829.782659780248;4948.356713627206;4949.604031677385;5027.446980832033;5037.395512538372;5015.322931404214;5055;5057.7724002419245;5076.111680455831;5088.084144853962 18:29:37 INFO - PROCESS | 1664 | |4;prototype.html;293.9970600293998;304.8795725688353;310.54815243820246;311.80823793367085;313.8368048614719;5506.944930550699;5508.889822203553;5530.4193059728705;5533.9485497755395;5552.555795536357;5155.7422128893595;5162.006036498831;5316.5215130638235;5436.646617969839;5479.589030822684;3243.4161850866835;3433.828308584561;4052.946233979157;4181.853635122764;4192.979035104835;4165.396017577449;4182.832686692529;4193.9580604194025;4198.37024446334;4214.36784482327;3255.5279484474736;3829.5404551453867;4127;4142.212979533879;4157.173122276866;3876.6898648108086;4064.9390259146135;4087.1417002429525;4101.3642885352665;4102.722988741262;3245.853936572836;3462.151772815651;3553.4314509678584;3565.5008298838184;3584.3368976739125;4384;4789.712617242976;4873.903371741365;4901.313816065754;5226.268322434862;3989.5212574491247;4218.767967761772;4315.093830295642;4747.548982846624;4774.28385742138;5086.847394578169;5412.91880621791;5437.510624043855;5456.963176996358;5719.3136823581435;3786.2616789726017;3989.68082553395;3993.221321842242;4007.539132999684;4014.4781178446906;4849.733264670477;5962.105684147413;5967.61210521319;5987.37132601075;5999.090441136045;4026.7583944963394;4041.6766658667234;4046.5953404659886;4046.656034237064;4063;3393.30437260366;4113.4858142732155;4113.670906327487;4124.319487284624;4125;4142.8400047986615;4469.597736203757;4520.186366454004;4710.317004034396;4734.431868175841;4890.633202509826;5049.4599147259705;5651.321841378978;5693.174489699053;5733.140028995684;1326.973460530784;1380.8342998840003;1405.206058576901;1410.5838777560643;1411.3719394869186;784.4704824243613;926.4441335198961;946.4037656276639;947.9336446448682;951.9619215231452;4514.458265008155;5250.317458730341;5266.683998960075;5301.257823904656;5354.143337066129;5324.41431442541;5783.2192653992015;5803.303603567599;5821.854453638693;5847;4782.641301902302;5152.536271735562;5360.946390536044;5490.917636235459;5512.834614961557;2685.650865387487;2689.663792025997;3100.9224769380944;3153.6373317068374;3175.714185723296;5597.824456864104;5953.005743076118;6179.69101544921;6191.81424557264;6226.346233645402;5185.688858668492;5186.844394668166;5229.215617657305;5362.410134885159;5874.882502350015;5265.78936842522;5439.211314359475;6164.845878852975;6171.814845554731;6196.938030619726;5033.370828646419;5386.784528618968;5738.454846789548;5825.825225243249;5909.409059094056;5636.85907852307;5766.29894181226;5801.680907550125;5847.75449625462;5880.794172203952;5641.038454811257;5776.277965254343;6157.658850510469;6176.7838125665385;6197.597156184834;3485.6165821759078;3836.6547010769164;3904.824282907333;3909.902252443712;3931.5282166140246;5007.649464537593;5460.972695136657;5952.886725212487;5965.373635768182;6010.87978240424;4429.778511074498;5510.338759348904;5548.722563871709;5815.4184581543195;5927.281088484464;5011.874703132451;5747.281589801275;5779.364269930388;5792.768289268551;5816.621919575214;5080;5106.514881086291;5184.196449550326;5453.727313634382;5577.27495425592;4953.439666505039;5301.5493683037375;5315.601329900195;5323.600729945192;5562.749676264659;5653.332266981168;5679.460451257124;5686.516646085129;5690.3456102548735;5713.257276554022 18:29:37 INFO - PROCESS | 1664 | |5;yui.html;692.5844493304021;775.8758598624221;782.1685498495445;795.840831833633;852.4230272884146;14547.854521454783;15090.019148755337;15703.450379236729;15727.292271847766;15728.685426291468;14087.647808804784;14142.646433839134;14157.929210353934;14185.361658725356;14194.148351098942;703.7220297982294;732.011784091475;869.1308691308675;869.3002133282705;895.7805337692274;889.0664801957951;893.8614514750224;894.5393122541897;894.6734441928681;900.9864852027225;930.6603089872216;933.6498812945146;933.8785957825507;934.6027938126269;934.8878134623855;729.7409419655999;759.6125975752351;760.1941941541983;762.085497403113;930.8417569013284;556.2962851992233;665.1320027364287;692.594832023267;692.9203141638675;694.2190036209265;12112.939435302855;14722.263886805616;14733.336999835034;14741;14746.41014359435;13432;13635.863641363658;13654.112482688593;13751.793723094162;13759.036867419285;12104.697382565419;12185;12213.389330533526;12317.507299708;12536.874631253662;4502.302143167814;4518.728876267402;4609.792559334838;4637.6289896808175;4679.321498382749;71968.92046619306;72221;72697.63651181763;72752;81917.59041204756;10675;10746.372837413466;10982.176336774774;10990.505427255792;11289;19170.808291917183;19991.20035198605;21294.25470108539;21296;21306.467338316666;7701.845963080819;7717.691292348243;7812.023497062867;7832.295093441618;7860.0567931848545;4529.320601909674;4532.501424843264;4533;4583.541645835457;4680.882977925579;3394.507796369562;3535.0455377048565;3537.25717599307;3539.1329124364697;3542.043648215019;648.2350826025241;678.1049015299757;688.769262297126;693.5214701855805;698.8637215742881;521.4264309259784;576.3602401334515;609.6829648582801;667.889798183304;706.4772068669148;8779.604917778715;8929;9305.302102342352;9414.435133891988;9555.761105972406;665.8768127896258;668.1681306773098;679.37497502298;688.9896651550231;700.250731717068;4005.739626924299;4331.242032644274;4404.383386325917;4473.194824931478;4629.8842528936375;85999.57000214959;86439;87640.5617971906;89106;91069;61723;73147.63426182941;80105.79841302385;80549;81018;48126.75936620295;62263;62465.687671561354;63198;68348;8394.202550757669;8522.701705440528;8580.270676992526;8623.310135189295;8685.748538887956;41921;44612.55387446084;44940.55059449364;45950.621481355614;47859;56069.158962614776;58755.706221468616;59895.700521497114;60779.392206079145;60913.39086609077;12411.689707757378;12526.87473125257;12540.87459125397;12749.49002039908;12872.970162386799;14007.929960350133;14288.57134285973;14328.785068223777;14392.208428536112;14580.781288280472;13031.739365212832;13293.26887021223;13566.487698857653;13761.146878873931;13785.203148013088;12834.229946203257;13109.934450327688;13284.202947823162;13312.667183320495;13353.599392018255;12753.744925101631;12883.35583220854;12963.22220666763;13008.934955325163;13415.262160581262;9561.474118923526;9668.226541876773;9687.564059617474;9702.41493137431;10033.698989030341;7439.144498382755;9164.098996123592;9464.337496375188;9496.667616633385;9797.55910983993 18:29:37 INFO - PROCESS | 1664 | __end_tp_report 18:29:37 INFO - PROCESS | 1664 | __start_cc_report 18:29:37 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1476 18:29:37 INFO - PROCESS | 1664 | __end_cc_report 18:29:37 INFO - PROCESS | 1664 | __startTimestamp1464398977659__endTimestamp 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | ------- Summary: start ------- 18:29:37 INFO - PROCESS | 1664 | Number of tests: 6 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5746.50 Median:5971.10 stddev:625.40 (10.5%) stddev-sans-first:600.35 18:29:37 INFO - PROCESS | 1664 | Values: 3430.5 3446.7 3466.7 3475.8 3526.7 5568.6 5589.5 5656.3 5722.2 5723.3 5586.8 5651.7 5651.9 5688.6 5695.1 5049.1 5098.1 5138.1 5162.2 5262.8 5271.6 5279.3 5285.3 5324.5 5361.8 5125.2 5162.2 5174.0 5207.9 5229.9 5211.8 5223.6 5223.9 5225.2 5230.9 5128.6 5160.0 5182.8 5193.0 5216.1 5581.9 5636.7 5642.6 5655.2 5711.0 6101.0 6148.0 6170.7 6218.4 6229.1 6240.9 6254.2 6282.6 6292.3 6297.1 5706.2 5745.9 5746.7 5812.2 5860.0 6217.8 6250.4 6262.5 6263.8 6265.0 6146.5 6179.5 6189.6 6190.6 6192.4 6110.0 6161.2 6229.5 6266.7 6276.6 6224.6 6236.5 6246.0 6249.7 6252.9 6176.6 6239.7 6249.2 6265.2 6265.5 4841.6 4898.7 4900.5 4943.1 5009.0 4466.1 4475.9 4488.6 4513.0 4514.1 5632.1 5646.0 5672.0 5674.8 5683.3 6197.8 6285.9 6296.0 6298.4 6300.0 5697.7 5710.8 5712.2 5719.0 5728.3 5289.5 5345.5 5366.5 5368.9 5389.5 6228.4 6349.9 6353.0 6355.4 6368.8 6270.6 6280.6 6294.8 6300.7 6307.3 6174.3 6204.8 6229.5 6337.5 6355.4 6198.0 6198.8 6207.5 6220.2 6233.3 6241.8 6245.4 6252.6 6253.4 6260.0 5922.8 5971.1 5986.8 6033.3 6119.4 6057.9 6103.2 6105.5 6130.7 6141.0 5938.2 5981.5 5982.9 6042.7 6052.1 5858.0 5860.2 6132.7 6140.4 6147.7 5886.5 5911.7 5952.9 5974.3 6003.0 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:70282.45 Median:40038.00 stddev:150063.58 (374.8%) stddev-sans-first:150393.14 18:29:37 INFO - PROCESS | 1664 | Values: 524.8 525.3 528.7 532.2 533.8 27753.6 40891.6 43654.8 43695.1 43723.6 45143.5 45538.9 45742.6 45855.5 45900.8 8492.1 9383.9 9603.5 9612.3 9634.8 8586.9 8651.2 8663.9 8669.4 11031.4 11373.6 11605.2 11613.2 11629.1 11630.6 9945.3 11633.0 11736.4 11742.7 11745.4 8515.2 8746.3 8764.7 8853.7 11516.0 35807.5 40032.0 40671.6 42863.4 43103.0 3461.7 3473.1 3613.9 3620.8 3853.9 25707.7 31765.0 32010.0 32125.8 33017.5 3222.1 3266.1 3297.5 3313.1 3436.2 445128.0 449362.0 450124.0 450687.0 453607.5 62245.1 62538.7 62608.7 62714.0 62769.0 66127.0 66147.7 66203.3 66213.3 66258.0 16371.8 16955.7 16959.7 16974.7 16996.7 12971.5 13019.1 13032.1 13036.9 13050.5 15518.7 15910.8 17744.7 18013.5 18157.3 5860.5 6182.5 6209.2 6228.2 6248.0 960.6 964.6 1039.6 1116.7 1154.2 30683.5 30700.7 30714.0 31421.7 31931.8 1132.2 1177.8 1197.8 1209.7 1234.7 15878.0 16020.8 16100.5 16149.8 16481.7 653773.0 658376.0 658621.0 659548.0 660662.0 58586.7 59874.0 60023.1 60051.0 60152.0 1259.2 1284.0 1402.7 1430.8 1447.4 30594.7 32004.0 32474.5 32780.8 32821.0 54230.0 54989.0 55132.2 55149.7 55422.2 513288.0 549552.0 567237.0 572347.0 591600.6 32114.4 37525.2 47048.1 48403.9 48629.8 42946.0 47924.0 49302.5 49463.0 50665.7 39730.4 40044.0 40250.0 40367.6 41143.8 34431.8 39917.8 40557.8 40592.2 41118.0 38397.8 39044.6 39905.8 39989.0 40047.6 30522.4 33410.2 34232.5 35139.8 35167.6 21274.7 25731.0 25902.5 26276.2 26478.0 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34668.70 Median:2342.95 stddev:96026.97 (4098.6%) stddev-sans-first:96261.83 18:29:37 INFO - PROCESS | 1664 | Values: 378.9 379.1 380.1 391.4 396.1 15632.9 19957.5 20676.0 20678.9 20843.0 20167.1 20427.3 20509.8 20897.5 20993.5 4346.6 5783.2 6011.7 6059.9 6062.5 4084.1 4351.2 6269.2 6599.7 6608.7 6496.1 6575.0 6584.2 6589.9 6612.3 5952.2 6082.0 6556.1 6564.4 6567.8 3712.6 5386.5 5726.1 5734.1 5778.8 25004.5 25066.5 25614.9 25733.9 25906.5 2063.9 2200.0 2219.9 2466.0 3021.1 10355.6 10419.4 10423.6 10442.7 14237.9 1819.0 1841.2 1902.9 1919.3 2007.0 121776.0 128167.0 129354.0 130086.0 131831.0 11622.4 11650.8 11679.1 11699.4 11842.2 15004.5 15061.3 15072.9 15091.0 15091.4 7173.3 8411.2 8745.9 8833.2 8883.0 7027.3 8551.1 8568.8 8611.4 8703.7 4157.4 4495.4 4600.9 4609.2 4676.6 1655.1 1764.6 1933.1 1947.9 1954.3 919.7 928.5 948.6 956.8 987.9 19404.2 20331.6 20410.7 20530.6 20735.9 904.1 984.8 996.6 1015.4 1058.1 8844.6 11152.3 11162.4 11185.5 11225.9 540101.0 571885.0 581678.7 582339.0 582588.0 123537.0 124630.4 127005.0 131492.0 135836.0 980.2 1002.7 1025.4 1026.5 1028.7 17699.4 18581.0 18747.0 18795.1 18833.9 38562.6 54429.7 55357.7 56321.4 56338.0 76944.0 81208.0 81797.2 82048.0 82082.0 8154.6 8193.9 8216.8 8295.5 8404.4 10338.3 11255.6 12336.4 12571.6 13538.9 10753.2 11459.1 11623.7 11701.6 11766.5 9403.3 11129.8 11366.1 11507.3 11735.6 11678.6 11793.9 11936.5 11937.3 11940.6 8235.3 8372.2 8418.2 8536.3 8569.4 9965.0 10215.4 10430.2 10555.9 10641.8 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5673.96 Median:5021.38 stddev:3130.58 (62.3%) stddev-sans-first:3122.86 18:29:37 INFO - PROCESS | 1664 | Values: 1397.2 1404.8 1529.0 1739.4 1927.9 7826.3 8370.3 8638.5 8662.3 8683.5 8233.8 8545.6 8567.2 8682.2 8692.5 2347.7 2398.6 2402.3 2403.8 2406.0 2359.4 2391.8 2403.1 2404.3 2411.9 2368.7 2389.4 2398.0 2406.4 2406.8 2350.8 2354.3 2383.3 2392.1 2392.9 2326.6 2389.8 2416.7 2418.0 2425.5 7697.7 7929.4 8714.0 8770.5 8797.3 4876.3 5054.0 5054.0 5160.0 5385.4 3533.1 3609.3 3652.7 3674.1 3677.9 893.3 1014.9 1061.1 1107.4 1109.4 11388.4 11616.2 11633.7 11635.4 11636.8 4474.3 4715.3 4901.5 4912.6 4940.2 9924.5 9945.2 9962.2 9972.3 9973.0 5412.9 5414.2 5422.3 5423.0 5427.5 4745.5 4961.9 4965.7 4984.1 4986.7 4027.7 4035.8 4076.2 4134.7 4187.8 1096.3 1111.4 1282.1 1301.7 1311.2 666.5 815.3 855.8 941.2 997.2 5844.3 5851.1 5938.5 5944.2 6076.5 946.5 947.7 960.6 970.2 1007.4 3903.4 3948.2 3982.7 4013.7 4015.3 9834.7 10443.2 10450.8 10451.1 10487.1 10071.6 10242.1 10402.0 10412.5 10414.1 8102.8 8154.4 8227.6 8244.0 8275.2 5659.2 5683.8 5778.9 5795.2 5851.4 9915.4 10027.2 10036.6 10042.8 10061.2 9958.0 9987.2 10017.2 10261.4 10277.9 6990.6 7025.3 7095.9 7120.2 7123.3 7798.2 8183.0 8254.6 8268.7 8271.1 7364.3 7424.2 7458.5 7589.8 7618.7 7029.4 7299.1 7399.4 7532.0 7548.0 6925.2 7079.6 7083.7 7094.3 7097.6 4829.8 4948.4 4949.6 5027.4 5037.4 5015.3 5055.0 5057.8 5076.1 5088.1 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4602.83 Median:5184.94 stddev:1414.06 (27.3%) stddev-sans-first:1380.55 18:29:37 INFO - PROCESS | 1664 | Values: 294.0 304.9 310.5 311.8 313.8 5506.9 5508.9 5530.4 5533.9 5552.6 5155.7 5162.0 5316.5 5436.6 5479.6 3243.4 3433.8 4052.9 4181.9 4193.0 4165.4 4182.8 4194.0 4198.4 4214.4 3255.5 3829.5 4127.0 4142.2 4157.2 3876.7 4064.9 4087.1 4101.4 4102.7 3245.9 3462.2 3553.4 3565.5 3584.3 4384.0 4789.7 4873.9 4901.3 5226.3 3989.5 4218.8 4315.1 4747.5 4774.3 5086.8 5412.9 5437.5 5457.0 5719.3 3786.3 3989.7 3993.2 4007.5 4014.5 4849.7 5962.1 5967.6 5987.4 5999.1 4026.8 4041.7 4046.6 4046.7 4063.0 3393.3 4113.5 4113.7 4124.3 4125.0 4142.8 4469.6 4520.2 4710.3 4734.4 4890.6 5049.5 5651.3 5693.2 5733.1 1327.0 1380.8 1405.2 1410.6 1411.4 784.5 926.4 946.4 947.9 952.0 4514.5 5250.3 5266.7 5301.3 5354.1 5324.4 5783.2 5803.3 5821.9 5847.0 4782.6 5152.5 5360.9 5490.9 5512.8 2685.7 2689.7 3100.9 3153.6 3175.7 5597.8 5953.0 6179.7 6191.8 6226.3 5185.7 5186.8 5229.2 5362.4 5874.9 5265.8 5439.2 6164.8 6171.8 6196.9 5033.4 5386.8 5738.5 5825.8 5909.4 5636.9 5766.3 5801.7 5847.8 5880.8 5641.0 5776.3 6157.7 6176.8 6197.6 3485.6 3836.7 3904.8 3909.9 3931.5 5007.6 5461.0 5952.9 5965.4 6010.9 4429.8 5510.3 5548.7 5815.4 5927.3 5011.9 5747.3 5779.4 5792.8 5816.6 5080.0 5106.5 5184.2 5453.7 5577.3 4953.4 5301.5 5315.6 5323.6 5562.7 5653.3 5679.5 5686.5 5690.3 5713.3 18:29:37 INFO - PROCESS | 1664 | 18:29:37 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17747.99 Median:29666.03 stddev:23558.33 (79.4%) stddev-sans-first:23589.61 18:29:37 INFO - PROCESS | 1664 | Values: 692.6 775.9 782.2 795.8 852.4 14547.9 15090.0 15703.5 15727.3 15728.7 14087.6 14142.6 14157.9 14185.4 14194.1 703.7 732.0 869.1 869.3 895.8 889.1 893.9 894.5 894.7 901.0 930.7 933.6 933.9 934.6 934.9 729.7 759.6 760.2 762.1 930.8 556.3 665.1 692.6 692.9 694.2 12112.9 14722.3 14733.3 14741.0 14746.4 13432.0 13635.9 13654.1 13751.8 13759.0 12104.7 12185.0 12213.4 12317.5 12536.9 4502.3 4518.7 4609.8 4637.6 4679.3 71968.9 72221.0 72697.6 72752.0 81917.6 10675.0 10746.4 10982.2 10990.5 11289.0 19170.8 19991.2 21294.3 21296.0 21306.5 7701.8 7717.7 7812.0 7832.3 7860.1 4529.3 4532.5 4533.0 4583.5 4680.9 3394.5 3535.0 3537.3 3539.1 3542.0 648.2 678.1 688.8 693.5 698.9 521.4 576.4 609.7 667.9 706.5 8779.6 8929.0 9305.3 9414.4 9555.8 665.9 668.2 679.4 689.0 700.3 4005.7 4331.2 4404.4 4473.2 4629.9 85999.6 86439.0 87640.6 89106.0 91069.0 61723.0 73147.6 80105.8 80549.0 81018.0 48126.8 62263.0 62465.7 63198.0 68348.0 8394.2 8522.7 8580.3 8623.3 8685.7 41921.0 44612.6 44940.6 45950.6 47859.0 56069.2 58755.7 59895.7 60779.4 60913.4 12411.7 12526.9 12540.9 12749.5 12873.0 14007.9 14288.6 14328.8 14392.2 14580.8 13031.7 13293.3 13566.5 13761.1 13785.2 12834.2 13109.9 13284.2 13312.7 13353.6 12753.7 12883.4 12963.2 13008.9 13415.3 9561.5 9668.2 9687.6 9702.4 10033.7 7439.1 9164.1 9464.3 9496.7 9797.6 18:29:37 INFO - PROCESS | 1664 | -------- Summary: end -------- 18:29:37 INFO - PROCESS | 1664 | 18:29:38 INFO - TEST-INFO | 1664: exit 0 18:29:38 INFO - TEST-OK | dromaeo_css | took 1117053ms 18:29:38 INFO - TEST-START | kraken 18:29:38 INFO - operating with platform_type : mac_ 18:29:38 INFO - Initialising browser for kraken test... 18:29:38 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpZHrnxL/profile http://localhost:49271/getInfo.html) 18:29:40 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 18:29:40 INFO - PROCESS | 1670 | colorDepth:24 18:29:40 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 18:29:40 INFO - PROCESS | 1670 | __metrics 18:29:40 INFO - PROCESS | 1670 | 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:29:40 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:29:41 INFO - TEST-INFO | 1670: exit 0 18:29:41 INFO - Browser initialized. 18:29:41 INFO - Running cycle 1/1 for kraken test... 18:29:41 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpZHrnxL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:29:53 INFO - PROCESS | 1671 | RSS: Main: 241643520 18:29:53 INFO - PROCESS | 1671 | 18:29:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 18:29:56 INFO - PROCESS | 1671 | RSS: Main: 290844672 18:29:56 INFO - PROCESS | 1671 | 18:29:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 18:30:00 INFO - PROCESS | 1671 | RSS: Main: 300888064 18:30:00 INFO - PROCESS | 1671 | 18:30:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 18:30:04 INFO - PROCESS | 1671 | RSS: Main: 386875392 18:30:04 INFO - PROCESS | 1671 | 18:30:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 18:30:06 INFO - PROCESS | 1671 | RSS: Main: 287608832 18:30:06 INFO - PROCESS | 1671 | 18:30:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:30:09 INFO - PROCESS | 1671 | RSS: Main: 290496512 18:30:09 INFO - PROCESS | 1671 | 18:30:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 18:30:12 INFO - PROCESS | 1671 | RSS: Main: 328613888 18:30:12 INFO - PROCESS | 1671 | 18:30:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 18:30:16 INFO - PROCESS | 1671 | RSS: Main: 328359936 18:30:16 INFO - PROCESS | 1671 | 18:30:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 18:30:19 INFO - PROCESS | 1671 | RSS: Main: 326500352 18:30:19 INFO - PROCESS | 1671 | 18:30:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:30:22 INFO - PROCESS | 1671 | RSS: Main: 309145600 18:30:22 INFO - PROCESS | 1671 | 18:30:23 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:30:24 INFO - PROCESS | 1671 | RSS: Main: 316977152 18:30:24 INFO - PROCESS | 1671 | 18:30:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:30:27 INFO - PROCESS | 1671 | RSS: Main: 321011712 18:30:27 INFO - PROCESS | 1671 | 18:30:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:30:30 INFO - PROCESS | 1671 | RSS: Main: 322183168 18:30:30 INFO - PROCESS | 1671 | 18:30:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:30:34 INFO - PROCESS | 1671 | RSS: Main: 322756608 18:30:34 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 18:30:36 INFO - PROCESS | 1671 | RSS: Main: 366501888 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | __start_tp_report 18:30:36 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 18:30:36 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 18:30:36 INFO - PROCESS | 1671 | |i|pagename|runs| 18:30:36 INFO - PROCESS | 1671 | |0;ai-astar;114;108;113;112;113;112;112;113;112;111 18:30:36 INFO - PROCESS | 1671 | |1;audio-beat-detection;158;120;117;168;118;116;139;119;152;126 18:30:36 INFO - PROCESS | 1671 | |2;audio-dft;184;186;184;182;183;181;183;183;181;182 18:30:36 INFO - PROCESS | 1671 | |3;audio-fft;79;74;69;106;56;57;60;63;122;57 18:30:36 INFO - PROCESS | 1671 | |4;audio-oscillator;82;91;95;69;71;69;114;99;98;104 18:30:36 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;91;89;92;89;91;88;92;89;92;88 18:30:36 INFO - PROCESS | 1671 | |6;imaging-darkroom;97;97;97;97;96;95;98;96;96;96 18:30:36 INFO - PROCESS | 1671 | |7;imaging-desaturate;84;84;84;83;85;86;86;83;85;90 18:30:36 INFO - PROCESS | 1671 | |8;json-parse-financial;56;57;56;57;56;55;55;55;56;56 18:30:36 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;73;69;81;75;75;71;71;70;70 18:30:36 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;85;86;83;85;85;86;85;87;121;88 18:30:36 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;141;138;141;136;134;137;137;136;160;145 18:30:36 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;210;186;166;166;166;166;169;165;165;163 18:30:36 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;66;66;65;66;65;66;65;64;65 18:30:36 INFO - PROCESS | 1671 | __end_tp_report 18:30:36 INFO - PROCESS | 1671 | __start_cc_report 18:30:36 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1984 18:30:36 INFO - PROCESS | 1671 | __end_cc_report 18:30:36 INFO - PROCESS | 1671 | __startTimestamp1464399036293__endTimestamp 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | ------- Summary: start ------- 18:30:36 INFO - PROCESS | 1671 | Number of tests: 14 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:1.63 (1.5%) stddev-sans-first:1.56 18:30:36 INFO - PROCESS | 1671 | Values: 114.0 108.0 113.0 112.0 113.0 112.0 112.0 113.0 112.0 111.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:132.50 stddev:19.52 (14.7%) stddev-sans-first:18.55 18:30:36 INFO - PROCESS | 1671 | Values: 158.0 120.0 117.0 168.0 118.0 116.0 139.0 119.0 152.0 126.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.52 (0.8%) stddev-sans-first:1.56 18:30:36 INFO - PROCESS | 1671 | Values: 184.0 186.0 184.0 182.0 183.0 181.0 183.0 183.0 181.0 182.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:74.30 Median:61.50 stddev:22.60 (36.7%) stddev-sans-first:23.90 18:30:36 INFO - PROCESS | 1671 | Values: 79.0 74.0 69.0 106.0 56.0 57.0 60.0 63.0 122.0 57.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:86.50 stddev:15.79 (18.3%) stddev-sans-first:16.53 18:30:36 INFO - PROCESS | 1671 | Values: 82.0 91.0 95.0 69.0 71.0 69.0 114.0 99.0 98.0 104.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:91.00 stddev:1.66 (1.8%) stddev-sans-first:1.73 18:30:36 INFO - PROCESS | 1671 | Values: 91.0 89.0 92.0 89.0 91.0 88.0 92.0 89.0 92.0 88.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:96.50 Median:97.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 18:30:36 INFO - PROCESS | 1671 | Values: 97.0 97.0 97.0 97.0 96.0 95.0 98.0 96.0 96.0 96.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:2.05 (2.4%) stddev-sans-first:2.15 18:30:36 INFO - PROCESS | 1671 | Values: 84.0 84.0 84.0 83.0 85.0 86.0 86.0 83.0 85.0 90.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:0.74 (1.3%) stddev-sans-first:0.78 18:30:36 INFO - PROCESS | 1671 | Values: 56.0 57.0 56.0 57.0 56.0 55.0 55.0 55.0 56.0 56.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:4.32 (6.0%) stddev-sans-first:3.77 18:30:36 INFO - PROCESS | 1671 | Values: 65.0 73.0 69.0 81.0 75.0 75.0 71.0 71.0 70.0 70.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:85.50 stddev:11.29 (13.2%) stddev-sans-first:11.88 18:30:36 INFO - PROCESS | 1671 | Values: 85.0 86.0 83.0 85.0 85.0 86.0 85.0 87.0 121.0 88.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:140.50 Median:139.50 stddev:7.56 (5.4%) stddev-sans-first:8.02 18:30:36 INFO - PROCESS | 1671 | Values: 141.0 138.0 141.0 136.0 134.0 137.0 137.0 136.0 160.0 145.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:166.00 stddev:14.80 (8.9%) stddev-sans-first:6.93 18:30:36 INFO - PROCESS | 1671 | Values: 210.0 186.0 166.0 166.0 166.0 166.0 169.0 165.0 165.0 163.0 18:30:36 INFO - PROCESS | 1671 | 18:30:36 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 18:30:36 INFO - PROCESS | 1671 | Values: 66.0 66.0 66.0 65.0 66.0 65.0 66.0 65.0 64.0 65.0 18:30:36 INFO - PROCESS | 1671 | -------- Summary: end -------- 18:30:36 INFO - PROCESS | 1671 | 18:30:37 INFO - TEST-INFO | 1671: exit 0 18:30:37 INFO - TEST-OK | kraken | took 58841ms 18:30:37 INFO - SUITE-END | took 1175s 18:30:37 INFO - Completed test suite (00:19:36) 18:30:37 INFO - javascript benchmark 18:30:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3430.5368775215343, 3446.707029902458, 3466.7399945004117, 3475.843587038583, 3526.735494837886, 5568.582356323277, 5589.469000444951, 5656.2929633795775, 5722.198892155091, 5723.255976723025, 5586.776528938848, 5651.745671444784, 5651.943480565194, 5688.573356998221, 5695.117256825199, 5049.091163590552, 5098.107831129556, 5138.126518491866, 5162.199859021839, 5262.789488420459, 5271.630985830993, 5279.2872962150195, 5285.312909321802, 5324.467553244684, 5361.847202851386, 5125.179971204627, 5162.22566615007, 5173.974130129325, 5207.895842083156, 5229.869253268661, 5211.791528338862, 5223.608229382811, 5223.869403264911, 5225.16397376418, 5230.92153617696, 5128.5640720538795, 5160.045391602565, 5182.766775495112, 5192.974035129838, 5216.087184742692, 5581.944180558224, 5636.718164091779, 5642.605017648766, 5655.236543066674, 5710.972025035492, 6101.017169419956, 6147.969260153715, 6170.691465426711, 6218.378162183791, 6229.09678096678, 6240.875182496325, 6254.186955695731, 6282.623042617412, 6292.339304373067, 6297.149884765544, 5706.229658996065, 5745.85635359111, 5746.712664366765, 5812.244408226988, 5859.970700146472, 6217.813465596039, 6250.406211409909, 6262.467690246289, 6263.843403914939, 6265.028920517327, 6146.508279337731, 6179.5056395488255, 6189.597676151036, 6190.628562286277, 6192.442680158807, 6109.95128740435, 6161.199044124235, 6229.470495007975, 6266.749330026839, 6276.560640755103, 6224.626522408579, 6236.469900058546, 6246.031865060922, 6249.6875156242, 6252.937470625236, 6176.629402235881, 6239.719212635442, 6249.1563638908165, 6265.154204182377, 6265.467435267962, 4841.612670986313, 4898.657094003457, 4900.5099490051425, 4943.085529177114, 5008.974955125201, 4466.129104824528, 4475.888102797391, 4488.551144885485, 4513.029698614813, 4514.142312960527, 5632.070708333161, 5645.971770141123, 5672.035753921843, 5674.801381951695, 5683.2895888014, 6197.752089916443, 6285.9057114143325, 6295.968520157461, 6298.401651843067, 6300.0, 5697.743601537941, 5710.828675139753, 5712.171735098387, 5719.0, 5728.341240757366, 5289.523942845162, 5345.492178243061, 5366.463353664681, 5368.892622147535, 5389.51494365509, 6228.439440450381, 6349.920513512714, 6353.0, 6355.3644635537, 6368.840778980562, 6270.592411493239, 6280.623162610258, 6294.8111556653375, 6300.684965751602, 6307.337729538516, 6174.320824709368, 6204.81385558434, 6229.470495007975, 6337.4613157882095, 6355.396237357444, 6197.96901015492, 6198.81403557894, 6207.534434917489, 6220.222472190976, 6233.345498722751, 6241.812745617639, 6245.437910588069, 6252.5935814171935, 6253.437190652863, 6259.96870015647, 5922.822315330547, 5971.104334349882, 5986.820395388146, 6033.306169790355, 6119.388061193845, 6057.878842423039, 6103.176071230327, 6105.45050945417, 6130.7241174146375, 6141.048137538689, 5938.2280303560265, 5981.52147828164, 5982.940170598238, 6042.728077236447, 6052.092186172109, 5858.0, 5860.150278209721, 6132.6627035513475, 6140.3552626975315, 6147.692615369303, 5886.470217680429, 5911.733971971357, 5952.851178720567, 5974.283086029531, 6002.979493486205], "value": 5707.104770431719, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.8058218459166, 525.2777431032329, 528.6616565398144, 532.2123257578783, 533.7838175538906, 27753.583696244546, 40891.59108408919, 43654.78172609132, 43695.12609747811, 43723.56276437235, 45143.54856451443, 45538.861528461806, 45742.56930730192, 45855.54144458554, 45900.770496147474, 8492.065872753992, 9383.859242111357, 9603.519824008807, 9612.327137100407, 9634.807303853919, 8586.87119693205, 8651.178138076873, 8663.870041949376, 8669.393142480028, 11031.448427578629, 11373.601923932663, 11605.245659032178, 11613.18707690462, 11629.127815413836, 11630.592929247478, 9945.303828731992, 11632.953034226917, 11736.354500502512, 11742.70643233926, 11745.354005529693, 8515.233628973423, 8746.300295976307, 8764.693235736782, 8853.69012084574, 11516.0, 35807.4628880567, 40032.0, 40671.59328406737, 42863.35704964428, 43103.0, 3461.7230621550216, 3473.114355839245, 3613.9096522586883, 3620.7827530348263, 3853.8651147209994, 25707.74292257072, 31765.0, 32010.0, 32125.83937080323, 33017.50473742896, 3222.0978126124487, 3266.055956902753, 3297.5383446317296, 3313.072339744875, 3436.2096717754807, 445128.0, 449362.0, 450124.0, 450687.0, 453607.4639253598, 62245.06632400517, 62538.68730656317, 62608.686956564925, 62714.0, 62769.0, 66127.0, 66147.66926165434, 66203.33796662067, 66213.33786662071, 66258.0183891726, 16371.754423683655, 16955.660886782443, 16959.660806783795, 16974.745378819327, 16996.660066798842, 12971.481140754264, 13019.088663793444, 13032.087753857139, 13036.869631303754, 13050.54323098687, 15518.68091212237, 15910.76133857993, 17744.733828992576, 18013.549661258574, 18157.27370905149, 5860.472557469848, 6182.474489668339, 6209.19280493542, 6228.159198508143, 6248.0, 960.5677445149711, 964.625408792468, 1039.6101461951769, 1116.6594188772503, 1154.1805318223987, 30683.539746903814, 30700.69299307023, 30714.0, 31421.685783142333, 31931.84034079861, 1132.201797732613, 1177.8351030855688, 1197.7903866823272, 1209.7399059202314, 1234.6789834642877, 15878.0, 16020.75968860468, 16100.516984490485, 16149.757753633705, 16481.6703665926, 653773.0, 658376.0, 658621.0, 659548.0, 660662.0, 58586.70706646525, 59874.0, 60023.09965350523, 60051.0, 60152.0, 1259.1563652352763, 1283.9935800321125, 1402.7475054490085, 1430.7782293744485, 1447.3848614339074, 30594.694053059186, 32004.0, 32474.512882306313, 32780.836095819366, 32821.0, 54230.0, 54989.0, 55132.17301740557, 55149.72425137849, 55422.168667470825, 513288.0, 549552.0, 567237.0, 572347.0, 591600.5879588388, 32114.357712845143, 37525.249495010496, 47048.059038819716, 48403.91758865269, 48629.75685121552, 42946.00944822215, 47924.0, 49302.506974931224, 49463.01073978572, 50665.74667126641, 39730.40404393878, 40043.998900027735, 40250.0, 40367.596324036385, 41143.7942810284, 34431.827840860635, 39917.800410997756, 40557.797211013756, 40592.1881562373, 41117.97205069897, 38397.808010960885, 39044.6095539041, 39905.800470997456, 39989.000274993356, 40047.599524004385, 30522.389552209275, 33410.16474588155, 34232.486512701835, 35139.82430087833, 35167.64832351746, 21274.680879786505, 25730.970761169345, 25902.481950361263, 26276.21171364862, 26478.0], "value": 20688.455352225432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.9374753165728, 379.1412450798939, 380.0896852039366, 391.42656008946886, 396.142351808335, 15632.921835390822, 19957.501062473428, 20676.0, 20678.89660551695, 20843.0, 20167.09248083836, 20427.285045023425, 20509.794902050977, 20897.477563060933, 20993.47516312093, 4346.565343465644, 5783.161441590967, 6011.669358185298, 6059.909101363483, 6062.51468390245, 4084.101497670508, 4351.238533256684, 6269.153664255336, 6599.703013364386, 6608.702608382634, 6496.090547323355, 6575.0, 6584.176977877765, 6589.93410065898, 6612.338766123373, 5952.226210592596, 6081.969590152065, 6556.08214849919, 6564.442022428076, 6567.770128045543, 3712.6472985066644, 5386.542143917772, 5726.141078838166, 5734.111212762028, 5778.8266352009505, 25004.49991000188, 25066.498670026496, 25614.87192564044, 25733.871330643226, 25906.481870362677, 2063.876167429944, 2199.967000494994, 2219.8557093789013, 2466.0382450844186, 3021.123874076515, 10355.585776569003, 10419.374837509698, 10423.583056677724, 10442.68671939843, 14237.93215508841, 1819.0359109671895, 1841.24161425838, 1902.9429117126508, 1919.3474218765548, 2006.9698954515693, 121776.0, 128166.99999999907, 129354.0, 130086.0, 131831.0, 11622.418879056013, 11650.825237621442, 11679.065674746, 11699.356535390636, 11842.230255033395, 15004.549863503895, 15061.322240499203, 15072.924635376752, 15091.01908375974, 15091.396344146331, 7173.318534739192, 8411.242988131098, 8745.851493462349, 8833.249173820297, 8883.022867484571, 7027.332403421667, 8551.102134275936, 8568.785780355416, 8611.440256383315, 8703.695370661997, 4157.376393540932, 4495.370648109268, 4600.930986035212, 4609.216433206363, 4676.579107880307, 1655.0566177278836, 1764.6382491589193, 1933.1011079847938, 1947.8733882297606, 1954.3355259211937, 919.6689191890914, 928.4939707859232, 948.5684013773716, 956.8134213828237, 987.8765154355706, 19404.2238310466, 20331.593368132555, 20410.69383959242, 20530.589388212153, 20735.8963205183, 904.0688091265876, 984.8424252119627, 996.5714742660581, 1015.4415071710678, 1058.0688993685508, 8844.55777211137, 11152.275102118336, 11162.386068766296, 11185.49665265065, 11225.887741122648, 540101.0, 571885.0, 581678.683307641, 582339.0, 582588.0, 123537.0, 124630.37684811518, 127005.0, 131492.0, 135836.0, 980.1521683743653, 1002.7242508310273, 1025.4462590201208, 1026.4662375564665, 1028.665683652816, 17699.380521682193, 18581.0, 18747.0, 18795.06024698787, 18833.869967801977, 38562.614373855904, 54429.72785136049, 55357.72321138369, 56321.43678563162, 56338.0, 76944.0, 81208.0, 81797.18202817896, 82048.0, 82082.0, 8154.592270386338, 8193.877091843746, 8216.753497395088, 8295.460795048302, 8404.369672274726, 10338.328008679411, 11255.649322081403, 12336.383180841101, 12571.622851314476, 13538.86461135376, 10753.1935104869, 11459.140564457866, 11623.65129046096, 11701.590444334408, 11766.529338826693, 9403.341766076583, 11129.833052504056, 11366.147538934447, 11507.309561426342, 11735.589254376056, 11678.6496405108, 11793.882061179278, 11936.462859171184, 11937.28376297425, 11940.641780746591, 8235.258826705385, 8372.162783721578, 8418.158184181533, 8536.274416674652, 8569.400141990001, 9964.950175249078, 10215.387076775418, 10430.217733669853, 10555.89444105549, 10641.840372394265], "value": 9656.030646986837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.2245403800898, 1404.7822587498938, 1528.9541313760587, 1739.4086010756341, 1927.9325223617168, 7826.295633392993, 8370.330373570116, 8638.524881131536, 8662.263707584847, 8683.478991260528, 8233.79415514611, 8545.572721363938, 8567.228949394554, 8682.218600325969, 8692.47845129291, 2347.7182738071456, 2398.556267090585, 2402.327348342458, 2403.759624037591, 2405.9759402406016, 2359.4101474631343, 2391.796697280733, 2403.098837935774, 2404.302752201859, 2411.9035238590436, 2368.7394386617457, 2389.426537630965, 2397.9520409591796, 2406.362313986795, 2406.7713567211176, 2350.7766762157567, 2354.2937118864443, 2383.2611890314056, 2392.091005417939, 2392.9042838286446, 2326.5579539887367, 2389.784919357266, 2416.6949847082556, 2417.9602770808547, 2425.5391475619576, 7697.653605587762, 7929.444938854283, 8714.0, 8770.473771573726, 8797.252233560155, 4876.341693871317, 5053.974730126326, 5053.974730126362, 5160.0, 5385.434529374437, 3533.0990597397754, 3609.314230296235, 3652.6712595866234, 3674.0631139059637, 3677.8712745054063, 893.3433926064338, 1014.852846337277, 1061.1139698351885, 1107.429673718036, 1109.4286442482128, 11388.430578471127, 11616.244944078608, 11633.6509904703, 11635.360055196961, 11636.767264654743, 4474.306482495218, 4715.339852420664, 4901.50984901507, 4912.631552633567, 4940.209566469348, 9924.503774811375, 9945.20438364929, 9962.203023758082, 9972.30193886421, 9973.0, 5412.91880621791, 5414.214938833847, 5422.295101636841, 5423.0, 5427.511523962783, 4745.454272758608, 4961.875953101129, 4965.702057876539, 4984.077945580079, 4986.700797952062, 4027.6989886739375, 4035.7982100894837, 4076.2051399977313, 4134.718237346432, 4187.811548480326, 1096.2600244834864, 1111.4498323330004, 1282.1153404151291, 1301.6745813546613, 1311.2263764379063, 666.5334266013809, 815.2703330519153, 855.7561095087871, 941.1858742188095, 997.1823105053932, 5844.298684157929, 5851.1223316502865, 5938.495227905676, 5944.227250457413, 6076.4531192192035, 946.4557879219476, 947.7488465556632, 960.582146766159, 970.2141265574907, 1007.3502590829021, 3903.394973779069, 3948.2498325318097, 3982.661473774762, 4013.6588389986596, 4015.3374693175883, 9834.704958851245, 10443.216758743125, 10450.843237351446, 10451.111655509214, 10487.056164945192, 10071.647492337734, 10242.078212960869, 10401.959804019538, 10412.531436085394, 10414.062734353945, 8102.7569172924905, 8154.3884208684585, 8227.588620568948, 8244.010718713673, 8275.172482751797, 5659.207710920557, 5683.8010669626365, 5778.8844223114465, 5795.246617939641, 5851.444112809277, 9915.405075695482, 10027.197824173903, 10036.64871729486, 10042.849357259793, 10061.245406594388, 9957.950210248902, 9987.151092157248, 10017.198624109908, 10261.435621040915, 10277.897221027994, 6990.580565166107, 7025.297470252934, 7095.929040709527, 7120.181179164255, 7123.252058533986, 7798.181190974866, 8183.0, 8254.587270636564, 8268.710595129141, 8271.131531189138, 7364.3003914628025, 7424.220456851953, 7458.47790654649, 7589.772306830804, 7618.69525218985, 7029.437644988287, 7299.124105107208, 7399.371053460516, 7531.962340188264, 7548.018757561482, 6925.238223795479, 7079.610621415872, 7083.716651333889, 7094.255103214088, 7097.574145551283, 4829.782659780248, 4948.356713627206, 4949.604031677385, 5027.446980832033, 5037.395512538372, 5015.322931404214, 5055.0, 5057.7724002419245, 5076.111680455831, 5088.084144853962], "value": 4575.4406079086675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.9970600293998, 304.8795725688353, 310.54815243820246, 311.80823793367085, 313.8368048614719, 5506.944930550699, 5508.889822203553, 5530.4193059728705, 5533.9485497755395, 5552.555795536357, 5155.7422128893595, 5162.006036498831, 5316.5215130638235, 5436.646617969839, 5479.589030822684, 3243.4161850866835, 3433.828308584561, 4052.946233979157, 4181.853635122764, 4192.979035104835, 4165.396017577449, 4182.832686692529, 4193.9580604194025, 4198.37024446334, 4214.36784482327, 3255.5279484474736, 3829.5404551453867, 4127.0, 4142.212979533879, 4157.173122276866, 3876.6898648108086, 4064.9390259146135, 4087.1417002429525, 4101.3642885352665, 4102.722988741262, 3245.853936572836, 3462.151772815651, 3553.4314509678584, 3565.5008298838184, 3584.3368976739125, 4384.0, 4789.712617242976, 4873.903371741365, 4901.313816065754, 5226.268322434862, 3989.5212574491247, 4218.767967761772, 4315.093830295642, 4747.548982846624, 4774.28385742138, 5086.847394578169, 5412.91880621791, 5437.510624043855, 5456.963176996358, 5719.3136823581435, 3786.2616789726017, 3989.68082553395, 3993.221321842242, 4007.539132999684, 4014.4781178446906, 4849.733264670477, 5962.105684147413, 5967.61210521319, 5987.37132601075, 5999.090441136045, 4026.7583944963394, 4041.6766658667234, 4046.5953404659886, 4046.656034237064, 4063.0, 3393.30437260366, 4113.4858142732155, 4113.670906327487, 4124.319487284624, 4125.0, 4142.8400047986615, 4469.597736203757, 4520.186366454004, 4710.317004034396, 4734.431868175841, 4890.633202509826, 5049.4599147259705, 5651.321841378978, 5693.174489699053, 5733.140028995684, 1326.973460530784, 1380.8342998840003, 1405.206058576901, 1410.5838777560643, 1411.3719394869186, 784.4704824243613, 926.4441335198961, 946.4037656276639, 947.9336446448682, 951.9619215231452, 4514.458265008155, 5250.317458730341, 5266.683998960075, 5301.257823904656, 5354.143337066129, 5324.41431442541, 5783.2192653992015, 5803.303603567599, 5821.854453638693, 5847.0, 4782.641301902302, 5152.536271735562, 5360.946390536044, 5490.917636235459, 5512.834614961557, 2685.650865387487, 2689.663792025997, 3100.9224769380944, 3153.6373317068374, 3175.714185723296, 5597.824456864104, 5953.005743076118, 6179.69101544921, 6191.81424557264, 6226.346233645402, 5185.688858668492, 5186.844394668166, 5229.215617657305, 5362.410134885159, 5874.882502350015, 5265.78936842522, 5439.211314359475, 6164.845878852975, 6171.814845554731, 6196.938030619726, 5033.370828646419, 5386.784528618968, 5738.454846789548, 5825.825225243249, 5909.409059094056, 5636.85907852307, 5766.29894181226, 5801.680907550125, 5847.75449625462, 5880.794172203952, 5641.038454811257, 5776.277965254343, 6157.658850510469, 6176.7838125665385, 6197.597156184834, 3485.6165821759078, 3836.6547010769164, 3904.824282907333, 3909.902252443712, 3931.5282166140246, 5007.649464537593, 5460.972695136657, 5952.886725212487, 5965.373635768182, 6010.87978240424, 4429.778511074498, 5510.338759348904, 5548.722563871709, 5815.4184581543195, 5927.281088484464, 5011.874703132451, 5747.281589801275, 5779.364269930388, 5792.768289268551, 5816.621919575214, 5080.0, 5106.514881086291, 5184.196449550326, 5453.727313634382, 5577.27495425592, 4953.439666505039, 5301.5493683037375, 5315.601329900195, 5323.600729945192, 5562.749676264659, 5653.332266981168, 5679.460451257124, 5686.516646085129, 5690.3456102548735, 5713.257276554022], "value": 4153.6589652614875, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.5844493304021, 775.8758598624221, 782.1685498495445, 795.840831833633, 852.4230272884146, 14547.854521454783, 15090.019148755337, 15703.450379236729, 15727.292271847766, 15728.685426291468, 14087.647808804784, 14142.646433839134, 14157.929210353934, 14185.361658725356, 14194.148351098942, 703.7220297982294, 732.011784091475, 869.1308691308675, 869.3002133282705, 895.7805337692274, 889.0664801957951, 893.8614514750224, 894.5393122541897, 894.6734441928681, 900.9864852027225, 930.6603089872216, 933.6498812945146, 933.8785957825507, 934.6027938126269, 934.8878134623855, 729.7409419655999, 759.6125975752351, 760.1941941541983, 762.085497403113, 930.8417569013284, 556.2962851992233, 665.1320027364287, 692.594832023267, 692.9203141638675, 694.2190036209265, 12112.939435302855, 14722.263886805616, 14733.336999835034, 14741.0, 14746.41014359435, 13432.0, 13635.863641363658, 13654.112482688593, 13751.793723094162, 13759.036867419285, 12104.697382565419, 12185.0, 12213.389330533526, 12317.507299708, 12536.874631253662, 4502.302143167814, 4518.728876267402, 4609.792559334838, 4637.6289896808175, 4679.321498382749, 71968.92046619306, 72221.0, 72697.63651181763, 72752.0, 81917.59041204756, 10675.0, 10746.372837413466, 10982.176336774774, 10990.505427255792, 11289.0, 19170.808291917183, 19991.20035198605, 21294.25470108539, 21296.0, 21306.467338316666, 7701.845963080819, 7717.691292348243, 7812.023497062867, 7832.295093441618, 7860.0567931848545, 4529.320601909674, 4532.501424843264, 4533.0, 4583.541645835457, 4680.882977925579, 3394.507796369562, 3535.0455377048565, 3537.25717599307, 3539.1329124364697, 3542.043648215019, 648.2350826025241, 678.1049015299757, 688.769262297126, 693.5214701855805, 698.8637215742881, 521.4264309259784, 576.3602401334515, 609.6829648582801, 667.889798183304, 706.4772068669148, 8779.604917778715, 8929.0, 9305.302102342352, 9414.435133891988, 9555.761105972406, 665.8768127896258, 668.1681306773098, 679.37497502298, 688.9896651550231, 700.250731717068, 4005.739626924299, 4331.242032644274, 4404.383386325917, 4473.194824931478, 4629.8842528936375, 85999.57000214959, 86439.0, 87640.5617971906, 89106.0, 91069.0, 61723.0, 73147.63426182941, 80105.79841302385, 80549.0, 81018.0, 48126.75936620295, 62263.0, 62465.687671561354, 63198.0, 68348.0, 8394.202550757669, 8522.701705440528, 8580.270676992526, 8623.310135189295, 8685.748538887956, 41921.0, 44612.55387446084, 44940.55059449364, 45950.621481355614, 47859.0, 56069.158962614776, 58755.706221468616, 59895.700521497114, 60779.392206079145, 60913.39086609077, 12411.689707757378, 12526.87473125257, 12540.87459125397, 12749.49002039908, 12872.970162386799, 14007.929960350133, 14288.57134285973, 14328.785068223777, 14392.208428536112, 14580.781288280472, 13031.739365212832, 13293.26887021223, 13566.487698857653, 13761.146878873931, 13785.203148013088, 12834.229946203257, 13109.934450327688, 13284.202947823162, 13312.667183320495, 13353.599392018255, 12753.744925101631, 12883.35583220854, 12963.22220666763, 13008.934955325163, 13415.262160581262, 9561.474118923526, 9668.226541876773, 9687.564059617474, 9702.41493137431, 10033.698989030341, 7439.144498382755, 9164.098996123592, 9464.337496375188, 9496.667616633385, 9797.55910983993], "value": 7140.12889142444, "unit": "score"}], "name": "dromaeo_css", "value": 7327.005406581354, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 113.0, 112.0, 113.0, 112.0, 112.0, 113.0, 112.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 120.0, 117.0, 168.0, 118.0, 116.0, 139.0, 119.0, 152.0, 126.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 182.0, 183.0, 181.0, 183.0, 183.0, 181.0, 182.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 69.0, 106.0, 56.0, 57.0, 60.0, 63.0, 122.0, 57.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 91.0, 95.0, 69.0, 71.0, 69.0, 114.0, 99.0, 98.0, 104.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 92.0, 89.0, 91.0, 88.0, 92.0, 89.0, 92.0, 88.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 97.0, 96.0, 95.0, 98.0, 96.0, 96.0, 96.0], "value": 96.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 83.0, 85.0, 86.0, 86.0, 83.0, 85.0, 90.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 57.0, 56.0, 55.0, 55.0, 55.0, 56.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 69.0, 81.0, 75.0, 75.0, 71.0, 71.0, 70.0, 70.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 85.0, 85.0, 86.0, 85.0, 87.0, 121.0, 88.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 141.0, 136.0, 134.0, 137.0, 137.0, 136.0, 160.0, 145.0], "value": 140.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 186.0, 166.0, 166.0, 166.0, 166.0, 169.0, 165.0, 165.0, 163.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 65.0, 64.0, 65.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1458.4, "alertThreshold": 2.0}]} 18:30:37 INFO - Return code: 0 18:30:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:30:37 ERROR - # TBPL SUCCESS # 18:30:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:30:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:30:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:30:37 INFO - 2016-05-27 18:30:37.628 screenresolution[1676:9679] starting screenresolution argv=screenresolution get 18:30:37 INFO - 2016-05-27 18:30:37.630 screenresolution[1676:9679] Display 0: 1600x1200x32@60 18:30:37 INFO - 2016-05-27 18:30:37.636 screenresolution[1677:9682] starting screenresolution argv=screenresolution list 18:30:37 INFO - Available Modes on Display 0 18:30:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:30:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:30:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:30:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:30:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:30:37 INFO - Intel Iris: 18:30:37 INFO - Chipset Model: Intel Iris 18:30:37 INFO - Type: GPU 18:30:37 INFO - Bus: Built-In 18:30:37 INFO - VRAM (Dynamic, Max): 1536 MB 18:30:37 INFO - Vendor: Intel (0x8086) 18:30:37 INFO - Device ID: 0x0a2e 18:30:37 INFO - Revision ID: 0x0009 18:30:37 INFO - Displays: 18:30:37 INFO - Display: 18:30:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:30:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:30:37 INFO - Display Serial Number: 1600x1200 60 18:30:37 INFO - Main Display: Yes 18:30:37 INFO - Mirror: Off 18:30:37 INFO - Online: Yes 18:30:37 INFO - Rotation: Supported 18:30:37 INFO - Return code: 0 18:30:37 INFO - Running post-action listener: _resource_record_post_action 18:30:37 INFO - ##### 18:30:37 INFO - ##### Finished run-tests step (success) 18:30:37 INFO - ##### 18:30:37 INFO - Running post-run listener: _resource_record_post_run 18:30:37 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32620032; Write bytes: 696745472; Read time: 418; Write time: 2284 18:30:37 INFO - TinderboxPrint: CPU usage
53.6% 18:30:37 INFO - TinderboxPrint: I/O read bytes / time
32,620,032 / 418 18:30:37 INFO - TinderboxPrint: I/O write bytes / time
696,745,472 / 2,284 18:30:37 INFO - TinderboxPrint: CPU idle
2,251.8 (46.4%) 18:30:37 INFO - TinderboxPrint: CPU system
967.6 (19.9%) 18:30:37 INFO - TinderboxPrint: CPU user
1,634.3 (33.7%) 18:30:37 INFO - TinderboxPrint: Swap in / out
426,471,424 / 0 18:30:37 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 387594752; Write bytes: 381421568; Read time: 30259; Write time: 685 18:30:37 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30850560; Write bytes: 295695872; Read time: 400; Write time: 1560 18:30:38 INFO - Running post-run listener: _upload_blobber_files 18:30:38 INFO - Blob upload gear active. 18:30:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:30:38 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:30:38 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:30:38 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:30:38 INFO - (blobuploader) - INFO - Open directory for files ... 18:30:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:30:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:30:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:30:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:39 INFO - (blobuploader) - INFO - Done attempting. 18:30:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:30:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:30:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:30:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:40 INFO - (blobuploader) - INFO - Done attempting. 18:30:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:30:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:30:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:30:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:30:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:30:43 INFO - (blobuploader) - INFO - Done attempting. 18:30:43 INFO - (blobuploader) - INFO - Iteration through files over. 18:30:43 INFO - Return code: 0 18:30:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:30:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:30:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3686fecaae9b88dc3c6554d77e7cd9e40ac37a4aab4d72836620e5e41a264641945bb953fd6da32ce7fc532e32b2716be4effa895d5743f4ba4df1964f613097", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02d5ffabf8dc1c7fb10bdfd212c08cec2b00417041bc25e3ef12fbdea810e5982f5be4b807ad8ac18a26291ca75855b1c2886d468b00e18ccf2076959856295b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77cb749521072347640da78323e191d142ea03858224101fd59c56d8377bbdd7232a801e777cf4099c79f03095474f2c06663ebbb47125d0cc2f495ec921b153"} 18:30:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:30:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:30:43 INFO - Contents: 18:30:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3686fecaae9b88dc3c6554d77e7cd9e40ac37a4aab4d72836620e5e41a264641945bb953fd6da32ce7fc532e32b2716be4effa895d5743f4ba4df1964f613097", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02d5ffabf8dc1c7fb10bdfd212c08cec2b00417041bc25e3ef12fbdea810e5982f5be4b807ad8ac18a26291ca75855b1c2886d468b00e18ccf2076959856295b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77cb749521072347640da78323e191d142ea03858224101fd59c56d8377bbdd7232a801e777cf4099c79f03095474f2c06663ebbb47125d0cc2f495ec921b153"} 18:30:43 INFO - Running post-run listener: copy_logs_to_upload_dir 18:30:43 INFO - Copying logs to upload dir... 18:30:43 INFO - mkdir: /builds/slave/test/build/upload/logs 18:30:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1292.304971 ========= master_lag: 2.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-27 18:30:46.426718) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 18:30:46.430391) ========= 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.HAeEojBL0a/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.R62gLrl6cs/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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/3686fecaae9b88dc3c6554d77e7cd9e40ac37a4aab4d72836620e5e41a264641945bb953fd6da32ce7fc532e32b2716be4effa895d5743f4ba4df1964f613097", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02d5ffabf8dc1c7fb10bdfd212c08cec2b00417041bc25e3ef12fbdea810e5982f5be4b807ad8ac18a26291ca75855b1c2886d468b00e18ccf2076959856295b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77cb749521072347640da78323e191d142ea03858224101fd59c56d8377bbdd7232a801e777cf4099c79f03095474f2c06663ebbb47125d0cc2f495ec921b153"} build_url:https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011087 build_url: 'https://queue.taskcluster.net/v1/task/QgfadrGDQCusj3AK60Zj9g/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/3686fecaae9b88dc3c6554d77e7cd9e40ac37a4aab4d72836620e5e41a264641945bb953fd6da32ce7fc532e32b2716be4effa895d5743f4ba4df1964f613097", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02d5ffabf8dc1c7fb10bdfd212c08cec2b00417041bc25e3ef12fbdea810e5982f5be4b807ad8ac18a26291ca75855b1c2886d468b00e18ccf2076959856295b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/77cb749521072347640da78323e191d142ea03858224101fd59c56d8377bbdd7232a801e777cf4099c79f03095474f2c06663ebbb47125d0cc2f495ec921b153"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 18:30:46.466004) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 18:30:46.466369) ========= 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.HAeEojBL0a/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.R62gLrl6cs/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.005221 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 18:30:46.514607) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 18:30:46.514908) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 18:30:46.515241) ========= ========= Total master_lag: 2.54 =========