builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0086 starttime: 1464099257.23 results: success (0) buildid: 20160524051813 builduid: 625de1765a064d5fa867a57242d0826c revision: 74fe05a5f015f517f835f0b560b074a941103481 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.227420) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.228297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.228664) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.288578) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.288959) ========= 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.8qid9oRzpB/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.cqweLfF1om/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-24 07:14:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.59M=0.001s 2016-05-24 07:14:17 (9.59 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.601700 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.909550) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.909879) ========= 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.8qid9oRzpB/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.cqweLfF1om/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.021974 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:17.958526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:14:17.958877) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 74fe05a5f015f517f835f0b560b074a941103481 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 74fe05a5f015f517f835f0b560b074a941103481 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8qid9oRzpB/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.cqweLfF1om/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-24 07:14:18,029 truncating revision to first 12 chars 2016-05-24 07:14:18,029 Setting DEBUG logging. 2016-05-24 07:14:18,030 attempt 1/10 2016-05-24 07:14:18,030 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/74fe05a5f015?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 07:14:18,959 unpacking tar archive at: fx-team-74fe05a5f015/testing/mozharness/ program finished with exit code 0 elapsedTime=1.178462 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-24 07:14:19.160290) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:19.160618) ========= script_repo_revision: 74fe05a5f015f517f835f0b560b074a941103481 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:19.161041) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:19.161340) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 07:14:19.177429) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-24 07:14:19.177760) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8qid9oRzpB/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.cqweLfF1om/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:14:19 INFO - MultiFileLogger online at 20160524 07:14:19 in /builds/slave/test 07:14:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:14:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:14:19 INFO - {'append_to_log': False, 07:14:19 INFO - 'base_work_dir': '/builds/slave/test', 07:14:19 INFO - 'blob_upload_branch': 'Fx-Team', 07:14:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:14:19 INFO - 'branch': 'Fx-Team', 07:14:19 INFO - 'buildbot_json_path': 'buildprops.json', 07:14:19 INFO - 'config_files': ('talos/mac_config.py',), 07:14:19 INFO - 'default_actions': ('clobber', 07:14:19 INFO - 'read-buildbot-config', 07:14:19 INFO - 'download-and-extract', 07:14:19 INFO - 'populate-webroot', 07:14:19 INFO - 'create-virtualenv', 07:14:19 INFO - 'install', 07:14:19 INFO - 'run-tests'), 07:14:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:14:19 INFO - 'download_minidump_stackwalk': True, 07:14:19 INFO - 'download_symbols': 'ondemand', 07:14:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:14:19 INFO - 'tooltool.py': '/tools/tooltool.py', 07:14:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:14:19 INFO - '/tools/misc-python/virtualenv.py')}, 07:14:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:14:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:14:19 INFO - 'installer_path': 'installer.exe', 07:14:19 INFO - 'log_level': 'info', 07:14:19 INFO - 'log_name': 'talos', 07:14:19 INFO - 'log_to_console': True, 07:14:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:14:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:14:19 INFO - 'opt_config_files': (), 07:14:19 INFO - 'pip_index': False, 07:14:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:14:19 INFO - 'cmd': ('bash', 07:14:19 INFO - '-c', 07:14:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:14:19 INFO - 'enabled': True, 07:14:19 INFO - 'halt_on_failure': False, 07:14:19 INFO - 'name': 'check_screen_resolution'},), 07:14:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:14:19 INFO - 'cmd': ('bash', 07:14:19 INFO - '-c', 07:14:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:14:19 INFO - 'enabled': True, 07:14:19 INFO - 'halt_on_failure': False, 07:14:19 INFO - 'name': 'check_screen_resolution'},), 07:14:19 INFO - 'run_cmd_checks_enabled': True, 07:14:19 INFO - 'sps_profile': False, 07:14:19 INFO - 'sps_profile_interval': 0, 07:14:19 INFO - 'suite': 'dromaeojs-e10s', 07:14:19 INFO - 'system_bits': '32', 07:14:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:14:19 INFO - 'title': 't-yosemite-r7-0086', 07:14:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:14:19 INFO - 'use_talos_json': True, 07:14:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:14:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:14:19 INFO - 'work_dir': 'build'} 07:14:19 INFO - ##### 07:14:19 INFO - ##### Running clobber step. 07:14:19 INFO - ##### 07:14:19 INFO - Running pre-action listener: _resource_record_pre_action 07:14:19 INFO - Running main action method: clobber 07:14:19 INFO - rmtree: /builds/slave/test/build 07:14:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:14:20 INFO - Running post-action listener: _resource_record_post_action 07:14:20 INFO - ##### 07:14:20 INFO - ##### Finished clobber step (success) 07:14:20 INFO - ##### 07:14:20 INFO - ##### 07:14:20 INFO - ##### Running read-buildbot-config step. 07:14:20 INFO - ##### 07:14:20 INFO - Running pre-action listener: _resource_record_pre_action 07:14:20 INFO - Running main action method: read_buildbot_config 07:14:20 INFO - Using buildbot properties: 07:14:20 INFO - { 07:14:20 INFO - "project": "", 07:14:20 INFO - "product": "firefox", 07:14:20 INFO - "script_repo_revision": "production", 07:14:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 07:14:20 INFO - "repository": "", 07:14:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 07:14:20 INFO - "buildid": "20160524051813", 07:14:20 INFO - "pgo_build": "False", 07:14:20 INFO - "basedir": "/builds/slave/test", 07:14:20 INFO - "buildnumber": 12, 07:14:20 INFO - "slavename": "t-yosemite-r7-0086", 07:14:20 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:14:20 INFO - "platform": "yosemite_r7", 07:14:20 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 07:14:20 INFO - "branch": "fx-team", 07:14:20 INFO - "repo_path": "integration/fx-team", 07:14:20 INFO - "revision": "74fe05a5f015f517f835f0b560b074a941103481", 07:14:20 INFO - "stage_platform": "macosx64", 07:14:20 INFO - "builduid": "625de1765a064d5fa867a57242d0826c", 07:14:20 INFO - "slavebuilddir": "test" 07:14:20 INFO - } 07:14:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:14:20 INFO - Running post-action listener: _resource_record_post_action 07:14:20 INFO - ##### 07:14:20 INFO - ##### Finished read-buildbot-config step (success) 07:14:20 INFO - ##### 07:14:20 INFO - ##### 07:14:20 INFO - ##### Running download-and-extract step. 07:14:20 INFO - ##### 07:14:20 INFO - Running pre-action listener: _resource_record_pre_action 07:14:20 INFO - Running main action method: download_and_extract 07:14:20 INFO - mkdir: /builds/slave/test/build/tests 07:14:20 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')]} 07:14:20 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:14:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:14:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 07:14:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:14:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 07:14:23 INFO - Downloaded 1199 bytes. 07:14:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:14:23 INFO - Using the following test package requirements: 07:14:23 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:14:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:14:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:14:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'jsshell-mac.zip'], 07:14:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:14:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:14:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:14:23 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:14:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:14:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:14:23 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:14:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:14:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:14:23 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:14:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:14:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:14:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:14:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:14:25 INFO - Downloaded 18630437 bytes. 07:14:25 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'] 07:14:25 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 07:14:28 INFO - Return code: 0 07:14:28 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 07:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:14:28 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:14:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:14:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:14:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 07:14:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:14:33 INFO - Downloaded 18630437 bytes. 07:14:33 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'] 07:14:33 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 07:14:37 INFO - Return code: 0 07:14:37 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')]} 07:14:37 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:14:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:14:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:14:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 07:14:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:14:38 INFO - Downloaded 10840359 bytes. 07:14:38 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'] 07:14:38 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 07:14:38 INFO - Return code: 0 07:14: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')]} 07:14:38 INFO - https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:14:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:14:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:14:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 07:14:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 07:14:43 INFO - Downloaded 110141401 bytes. 07:14:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:14:43 INFO - mkdir: /builds/slave/test/properties 07:14:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:14:43 INFO - Writing to file /builds/slave/test/properties/build_url 07:14:43 INFO - Contents: 07:14:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:14:44 INFO - Running post-action listener: _resource_record_post_action 07:14:44 INFO - Running post-action listener: set_extra_try_arguments 07:14:44 INFO - ##### 07:14:44 INFO - ##### Finished download-and-extract step (success) 07:14:44 INFO - ##### 07:14:44 INFO - ##### 07:14:44 INFO - ##### Running populate-webroot step. 07:14:44 INFO - ##### 07:14:44 INFO - Running pre-action listener: _resource_record_pre_action 07:14:44 INFO - Running main action method: populate_webroot 07:14:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:14:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:14:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:14:44 INFO - '--tppagecycles', 07:14:44 INFO - '7'], 07:14:44 INFO - 'tests': ['tsvgm']}}, 07:14:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:14:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:14:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:14:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:14:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:14:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:14:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['damp', 'tps']}, 07:14:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'tests': ['damp', 'tps']}, 07:14:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['dromaeo_dom']}, 07:14:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:14:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['a11yr', 07:14:44 INFO - 'ts_paint', 07:14:44 INFO - 'tpaint', 07:14:44 INFO - 'sessionrestore', 07:14:44 INFO - 'sessionrestore_no_auto_restore', 07:14:44 INFO - 'tabpaint']}, 07:14:44 INFO - 'other-e10s': {'tests': ['a11yr', 07:14:44 INFO - 'ts_paint', 07:14:44 INFO - 'tpaint', 07:14:44 INFO - 'sessionrestore', 07:14:44 INFO - 'sessionrestore_no_auto_restore', 07:14:44 INFO - 'tabpaint']}, 07:14:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:14:44 INFO - 'ts_paint', 07:14:44 INFO - 'tpaint', 07:14:44 INFO - 'sessionrestore', 07:14:44 INFO - 'sessionrestore_no_auto_restore', 07:14:44 INFO - 'tabpaint']}, 07:14:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:14:44 INFO - 'ts_paint', 07:14:44 INFO - 'tpaint', 07:14:44 INFO - 'sessionrestore', 07:14:44 INFO - 'sessionrestore_no_auto_restore', 07:14:44 INFO - 'tabpaint']}, 07:14:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['a11yr', 07:14:44 INFO - 'ts_paint', 07:14:44 INFO - 'tpaint', 07:14:44 INFO - 'sessionrestore', 07:14:44 INFO - 'sessionrestore_no_auto_restore', 07:14:44 INFO - 'tabpaint']}, 07:14:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['a11yr', 07:14:44 INFO - 'ts_paint', 07:14:44 INFO - 'tpaint', 07:14:44 INFO - 'sessionrestore', 07:14:44 INFO - 'sessionrestore_no_auto_restore', 07:14:44 INFO - 'tabpaint']}, 07:14:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['tsvgx', 07:14:44 INFO - 'tsvgr_opacity', 07:14:44 INFO - 'tart', 07:14:44 INFO - 'tscrollx', 07:14:44 INFO - 'cart']}, 07:14:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:14:44 INFO - 'tsvgr_opacity', 07:14:44 INFO - 'tart', 07:14:44 INFO - 'tscrollx', 07:14:44 INFO - 'cart']}, 07:14:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'talos_options': ['--disable-e10s'], 07:14:44 INFO - 'tests': ['tp5o']}, 07:14:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'tests': ['tp5o']}, 07:14:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'talos_options': ['--disable-e10s', 07:14:44 INFO - '--xperf_path', 07:14:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:14:44 INFO - 'tests': ['tp5n']}, 07:14:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:14:44 INFO - 'talos_options': ['--xperf_path', 07:14:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:14:44 INFO - 'tests': ['tp5n']}}, 07:14:44 INFO - 'talos.zip': {'path': '', 07:14:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:14:44 INFO - Running post-action listener: _resource_record_post_action 07:14:44 INFO - ##### 07:14:44 INFO - ##### Finished populate-webroot step (success) 07:14:44 INFO - ##### 07:14:44 INFO - ##### 07:14:44 INFO - ##### Running create-virtualenv step. 07:14:44 INFO - ##### 07:14:44 INFO - Running pre-action listener: _resource_record_pre_action 07:14:44 INFO - Running main action method: create_virtualenv 07:14:44 INFO - Creating virtualenv /builds/slave/test/build/venv 07:14:44 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 07:14:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:14:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:14:44 INFO - Using real prefix '/tools/python27' 07:14:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:14:45 INFO - Installing distribute.............................................................................................................................................................................................done. 07:14:48 INFO - Installing pip.................done. 07:14:48 INFO - Return code: 0 07:14:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:48 INFO - 'HOME': '/Users/cltbld', 07:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:48 INFO - 'LOGNAME': 'cltbld', 07:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:48 INFO - 'MOZ_NO_REMOTE': '1', 07:14:48 INFO - 'NO_EM_RESTART': '1', 07:14:48 INFO - 'PAGER': '/bin/cat', 07:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:48 INFO - 'PWD': '/builds/slave/test', 07:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:48 INFO - 'SHELL': '/bin/bash', 07:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:14:48 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:48 INFO - 'USER': 'cltbld', 07:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:48 INFO - 'XPC_FLAGS': '0x0', 07:14:48 INFO - 'XPC_SERVICE_NAME': '0', 07:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:48 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 07:14:48 INFO - Cleaning up... 07:14:48 INFO - Return code: 0 07:14:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:48 INFO - 'HOME': '/Users/cltbld', 07:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:48 INFO - 'LOGNAME': 'cltbld', 07:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:48 INFO - 'MOZ_NO_REMOTE': '1', 07:14:48 INFO - 'NO_EM_RESTART': '1', 07:14:48 INFO - 'PAGER': '/bin/cat', 07:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:48 INFO - 'PWD': '/builds/slave/test', 07:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:48 INFO - 'SHELL': '/bin/bash', 07:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:14:48 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:48 INFO - 'USER': 'cltbld', 07:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:48 INFO - 'XPC_FLAGS': '0x0', 07:14:48 INFO - 'XPC_SERVICE_NAME': '0', 07:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:48 INFO - Downloading/unpacking psutil>=3.1.1 07:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:14:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:14:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:14:52 INFO - Installing collected packages: psutil 07:14:52 INFO - Running setup.py install for psutil 07:14:52 INFO - building 'psutil._psutil_osx' extension 07:14:52 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 07:14:52 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 07:14:52 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 07:14:52 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 07:14:52 INFO - building 'psutil._psutil_posix' extension 07:14:52 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 07:14:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:14:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:14:52 INFO - ^ 07:14:52 INFO - 1 warning generated. 07:14:52 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 07:14:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:14:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:14:53 INFO - Successfully installed psutil 07:14:53 INFO - Cleaning up... 07:14:53 INFO - Return code: 0 07:14:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14: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')]} 07:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:14: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:14: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 mozsystemmonitor==0.3 07:14:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:14:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:53 INFO - 'HOME': '/Users/cltbld', 07:14:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:53 INFO - 'LOGNAME': 'cltbld', 07:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:53 INFO - 'MOZ_NO_REMOTE': '1', 07:14:53 INFO - 'NO_EM_RESTART': '1', 07:14:53 INFO - 'PAGER': '/bin/cat', 07:14:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:53 INFO - 'PWD': '/builds/slave/test', 07:14:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:53 INFO - 'SHELL': '/bin/bash', 07:14:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:14:53 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:14:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:53 INFO - 'USER': 'cltbld', 07:14:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:53 INFO - 'XPC_FLAGS': '0x0', 07:14:53 INFO - 'XPC_SERVICE_NAME': '0', 07:14:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:14: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 07:14: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 07:14: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 07:14: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 07:14: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 07:14: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 07:14:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:14:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:14:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:14:55 INFO - Installing collected packages: mozsystemmonitor 07:14:55 INFO - Running setup.py install for mozsystemmonitor 07:14:56 INFO - Successfully installed mozsystemmonitor 07:14:56 INFO - Cleaning up... 07:14:56 INFO - Return code: 0 07:14:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:14:56 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')]} 07:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:56 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')]} 07:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:56 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 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:14:56 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 07:14:56 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 07:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:56 INFO - 'HOME': '/Users/cltbld', 07:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:56 INFO - 'LOGNAME': 'cltbld', 07:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:56 INFO - 'MOZ_NO_REMOTE': '1', 07:14:56 INFO - 'NO_EM_RESTART': '1', 07:14:56 INFO - 'PAGER': '/bin/cat', 07:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:56 INFO - 'PWD': '/builds/slave/test', 07:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:56 INFO - 'SHELL': '/bin/bash', 07:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:14:56 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:56 INFO - 'USER': 'cltbld', 07:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:56 INFO - 'XPC_FLAGS': '0x0', 07:14:56 INFO - 'XPC_SERVICE_NAME': '0', 07:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:56 INFO - Downloading/unpacking blobuploader==1.2.4 07:14:56 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 07:14:56 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 07:14:56 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 07:14:56 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 07:14:56 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 07:14:56 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 07:14:58 INFO - Downloading blobuploader-1.2.4.tar.gz 07:14:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:14:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:14: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 07:14: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 07:14: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 07:14: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 07:14: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 07:14: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 07:14:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:14:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:15:00 INFO - Downloading docopt-0.6.1.tar.gz 07:15:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:15:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:15:00 INFO - Running setup.py install for blobuploader 07:15:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:15:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:15:00 INFO - Running setup.py install for requests 07:15:00 INFO - Running setup.py install for docopt 07:15:00 INFO - Successfully installed blobuploader requests docopt 07:15:00 INFO - Cleaning up... 07:15:01 INFO - Return code: 0 07:15:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:15: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')]} 07:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:15:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15: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')]} 07:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:15:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:01 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 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:15:01 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 07:15:01 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 07:15:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:15:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:01 INFO - 'HOME': '/Users/cltbld', 07:15:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:01 INFO - 'LOGNAME': 'cltbld', 07:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:01 INFO - 'MOZ_NO_REMOTE': '1', 07:15:01 INFO - 'NO_EM_RESTART': '1', 07:15:01 INFO - 'PAGER': '/bin/cat', 07:15:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:01 INFO - 'PWD': '/builds/slave/test', 07:15:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:01 INFO - 'SHELL': '/bin/bash', 07:15:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:15:01 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:15:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:01 INFO - 'USER': 'cltbld', 07:15:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:01 INFO - 'XPC_FLAGS': '0x0', 07:15:01 INFO - 'XPC_SERVICE_NAME': '0', 07:15:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:15:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-wI_Ei5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:15:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Hmj4IB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:15:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-z_lfHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:15:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-B_KWde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:15:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-XkbYj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:15:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-BTdpON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:15:01 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-G5o2sE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-yjq3HD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-mYkrBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-s27Vj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-2ccWLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-lZSoMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-VqcvOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-biqQuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-UtJClL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Z4Dw0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:15:02 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-myK9zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:15:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:15:03 INFO - Running setup.py install for manifestparser 07:15:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:15:03 INFO - Running setup.py install for mozcrash 07:15:03 INFO - Running setup.py install for mozdebug 07:15:03 INFO - Running setup.py install for mozdevice 07:15:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:15:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:15:03 INFO - Running setup.py install for mozfile 07:15:03 INFO - Running setup.py install for mozhttpd 07:15:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:15:03 INFO - Running setup.py install for mozinfo 07:15:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Running setup.py install for mozInstall 07:15:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Running setup.py install for mozleak 07:15:04 INFO - Running setup.py install for mozlog 07:15:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Running setup.py install for moznetwork 07:15:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Running setup.py install for mozprocess 07:15:04 INFO - Running setup.py install for mozprofile 07:15:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:15:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:15:05 INFO - Running setup.py install for mozrunner 07:15:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:15:05 INFO - Running setup.py install for mozscreenshot 07:15:05 INFO - Running setup.py install for moztest 07:15:05 INFO - Running setup.py install for mozversion 07:15:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:15:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:15:05 INFO - Cleaning up... 07:15:05 INFO - Return code: 0 07:15:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:15: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')]} 07:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:15:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15: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')]} 07:15:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:15:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:05 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 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:15:05 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 07:15:05 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 07:15:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:15:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:05 INFO - 'HOME': '/Users/cltbld', 07:15:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:05 INFO - 'LOGNAME': 'cltbld', 07:15:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:05 INFO - 'MOZ_NO_REMOTE': '1', 07:15:05 INFO - 'NO_EM_RESTART': '1', 07:15:05 INFO - 'PAGER': '/bin/cat', 07:15:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:05 INFO - 'PWD': '/builds/slave/test', 07:15:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:05 INFO - 'SHELL': '/bin/bash', 07:15:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:15:05 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:15:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:05 INFO - 'USER': 'cltbld', 07:15:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:05 INFO - 'XPC_FLAGS': '0x0', 07:15:05 INFO - 'XPC_SERVICE_NAME': '0', 07:15:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:15:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:15:05 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-H0CS8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-3ATDEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-eDE3tN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-F_uEXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-m1lgO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-PMCO8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-tLQI8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ErzSvq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-lFojBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-p77F97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:15:06 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)) 07:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:15:06 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-_mO4fL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:15:07 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)) 07:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:15:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-TVg2nu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:15:07 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)) 07:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:15:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-pwUCuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:15:07 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)) 07:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:15:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-SQvRn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:15:07 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)) 07:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:15:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-z7DhWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:15:07 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)) 07:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:15:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-byv5gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:15:07 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)) 07:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:15:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-hlZYIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:15:07 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)) 07:15:07 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)) 07:15:07 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)) 07:15:07 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)) 07:15:07 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)) 07:15:07 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)) 07:15:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:15:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:15:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:15:10 INFO - Downloading blessings-1.6.tar.gz 07:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:15:10 INFO - Installing collected packages: blessings 07:15:10 INFO - Running setup.py install for blessings 07:15:10 INFO - Successfully installed blessings 07:15:10 INFO - Cleaning up... 07:15:10 INFO - Return code: 0 07:15:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:15:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:15:10 INFO - Reading from file tmpfile_stdout 07:15:10 INFO - Current package versions: 07:15:10 INFO - blessings == 1.6 07:15:10 INFO - blobuploader == 1.2.4 07:15:10 INFO - docopt == 0.6.1 07:15:10 INFO - manifestparser == 1.1 07:15:10 INFO - mozInstall == 1.12 07:15:10 INFO - mozcrash == 0.17 07:15:10 INFO - mozdebug == 0.1 07:15:10 INFO - mozdevice == 0.48 07:15:10 INFO - mozfile == 1.2 07:15:10 INFO - mozhttpd == 0.7 07:15:10 INFO - mozinfo == 0.9 07:15:10 INFO - mozleak == 0.1 07:15:10 INFO - mozlog == 3.2 07:15:10 INFO - moznetwork == 0.27 07:15:10 INFO - mozprocess == 0.22 07:15:10 INFO - mozprofile == 0.28 07:15:10 INFO - mozrunner == 6.11 07:15:10 INFO - mozscreenshot == 0.1 07:15:10 INFO - mozsystemmonitor == 0.3 07:15:10 INFO - moztest == 0.7 07:15:10 INFO - mozversion == 1.4 07:15:10 INFO - psutil == 3.1.1 07:15:10 INFO - requests == 1.2.3 07:15:10 INFO - wsgiref == 0.1.2 07:15:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:15: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')]} 07:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:15:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15: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')]} 07:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:15:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:10 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 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:15:10 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 07:15:10 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 07:15:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:15:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:10 INFO - 'HOME': '/Users/cltbld', 07:15:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:10 INFO - 'LOGNAME': 'cltbld', 07:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:10 INFO - 'MOZ_NO_REMOTE': '1', 07:15:10 INFO - 'NO_EM_RESTART': '1', 07:15:10 INFO - 'PAGER': '/bin/cat', 07:15:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:10 INFO - 'PWD': '/builds/slave/test', 07:15:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:10 INFO - 'SHELL': '/bin/bash', 07:15:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:15:10 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:15:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:10 INFO - 'USER': 'cltbld', 07:15:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:10 INFO - 'XPC_FLAGS': '0x0', 07:15:10 INFO - 'XPC_SERVICE_NAME': '0', 07:15:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 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)) 07:15:11 INFO - Cleaning up... 07:15:11 INFO - Return code: 0 07:15:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:15:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:15:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:15:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:11 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 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:15:11 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 07:15:11 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 07:15:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:15:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:11 INFO - 'HOME': '/Users/cltbld', 07:15:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:11 INFO - 'LOGNAME': 'cltbld', 07:15:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:11 INFO - 'MOZ_NO_REMOTE': '1', 07:15:11 INFO - 'NO_EM_RESTART': '1', 07:15:11 INFO - 'PAGER': '/bin/cat', 07:15:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:11 INFO - 'PWD': '/builds/slave/test', 07:15:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:11 INFO - 'SHELL': '/bin/bash', 07:15:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:15:11 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:15:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:11 INFO - 'USER': 'cltbld', 07:15:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:11 INFO - 'XPC_FLAGS': '0x0', 07:15:11 INFO - 'XPC_SERVICE_NAME': '0', 07:15:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:15:11 INFO - Downloading/unpacking jsonschema 07:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:15:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:15:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:15:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:15:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:15:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:15:13 INFO - Downloading/unpacking functools32 (from jsonschema) 07:15:13 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 07:15:13 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 07:15:13 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 07:15:13 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 07:15:13 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 07:15:13 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 07:15:14 INFO - Downloading functools32-3.2.3-2.tar.gz 07:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:15:14 INFO - warning: no files found matching '*.txt' 07:15:14 INFO - no previously-included directories found matching 'build' 07:15:14 INFO - no previously-included directories found matching 'dist' 07:15:14 INFO - no previously-included directories found matching '.git*' 07:15:14 INFO - Installing collected packages: jsonschema, functools32 07:15:14 INFO - Running setup.py install for functools32 07:15:14 INFO - warning: no files found matching '*.txt' 07:15:14 INFO - no previously-included directories found matching 'build' 07:15:14 INFO - no previously-included directories found matching 'dist' 07:15:14 INFO - no previously-included directories found matching '.git*' 07:15:14 INFO - Successfully installed jsonschema functools32 07:15:14 INFO - Cleaning up... 07:15:14 INFO - Return code: 0 07:15:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:15:14 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')]} 07:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:15:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:14 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')]} 07:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:15:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:14 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 0x10d56ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d574570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadb0d51330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, '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.8qid9oRzpB/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.cqweLfF1om/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 07:15:14 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 07:15:14 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 07:15:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:15:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:14 INFO - 'HOME': '/Users/cltbld', 07:15:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:14 INFO - 'LOGNAME': 'cltbld', 07:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:14 INFO - 'MOZ_NO_REMOTE': '1', 07:15:14 INFO - 'NO_EM_RESTART': '1', 07:15:14 INFO - 'PAGER': '/bin/cat', 07:15:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:14 INFO - 'PWD': '/builds/slave/test', 07:15:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:14 INFO - 'SHELL': '/bin/bash', 07:15:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:15:14 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:15:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:14 INFO - 'USER': 'cltbld', 07:15:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:14 INFO - 'XPC_FLAGS': '0x0', 07:15:14 INFO - 'XPC_SERVICE_NAME': '0', 07:15:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:15:14 INFO - Downloading/unpacking flake8 07:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:15:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:15:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:15:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:15:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:15:17 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 07:15:17 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 07:15:17 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 07:15:17 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 07:15:17 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 07:15:17 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 07:15:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:15:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:15:17 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 07:15:17 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 07:15:17 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 07:15:17 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 07:15:17 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 07:15:17 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 07:15:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:15: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 07:15: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 07:15: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 07:15: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 07:15: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 07:15: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 07:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:15:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:15:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:15:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:15:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:15:18 INFO - no previously-included directories found matching 'docs/_build' 07:15:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:15:18 INFO - Running setup.py install for pep8 07:15:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:15:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:15:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:15:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:15:18 INFO - no previously-included directories found matching 'docs/_build' 07:15:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:15:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:15:18 INFO - Cleaning up... 07:15:19 INFO - Return code: 0 07:15:19 INFO - Running post-action listener: _resource_record_post_action 07:15:19 INFO - Running post-action listener: _start_resource_monitoring 07:15:19 INFO - Starting resource monitoring. 07:15:19 INFO - ##### 07:15:19 INFO - ##### Finished create-virtualenv step (success) 07:15:19 INFO - ##### 07:15:19 INFO - ##### 07:15:19 INFO - ##### Running install step. 07:15:19 INFO - ##### 07:15:19 INFO - Running pre-action listener: _resource_record_pre_action 07:15:19 INFO - Running main action method: install 07:15:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:15:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:15:19 INFO - Reading from file tmpfile_stdout 07:15:19 INFO - Detecting whether we're running mozinstall >=1.0... 07:15:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:15:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:15:19 INFO - Reading from file tmpfile_stdout 07:15:19 INFO - Output received: 07:15:19 INFO - Usage: mozinstall [options] installer 07:15:19 INFO - Options: 07:15:19 INFO - -h, --help show this help message and exit 07:15:19 INFO - -d DEST, --destination=DEST 07:15:19 INFO - Directory to install application into. [default: 07:15:19 INFO - "/builds/slave/test"] 07:15:19 INFO - --app=APP Application being installed. [default: firefox] 07:15:19 INFO - mkdir: /builds/slave/test/build/application 07:15:19 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'] 07:15:19 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 07:15:53 INFO - Reading from file tmpfile_stdout 07:15:53 INFO - Output received: 07:15:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:15:53 INFO - Running post-action listener: _resource_record_post_action 07:15:53 INFO - ##### 07:15:53 INFO - ##### Finished install step (success) 07:15:53 INFO - ##### 07:15:53 INFO - ##### 07:15:53 INFO - ##### Running run-tests step. 07:15:53 INFO - ##### 07:15:53 INFO - Running pre-action listener: _resource_record_pre_action 07:15:53 INFO - Running main action method: run_tests 07:15:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:15:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:15:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:15:53 INFO - 2016-05-24 07:15:53.168 screenresolution[2178:19706] starting screenresolution argv=screenresolution get 07:15:53 INFO - 2016-05-24 07:15:53.170 screenresolution[2178:19706] Display 0: 1600x1200x32@60 07:15:53 INFO - 2016-05-24 07:15:53.175 screenresolution[2179:19708] starting screenresolution argv=screenresolution list 07:15:53 INFO - Available Modes on Display 0 07:15:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:15:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:15:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:15:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:15:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:15:53 INFO - Intel Iris: 07:15:53 INFO - Chipset Model: Intel Iris 07:15:53 INFO - Type: GPU 07:15:53 INFO - Bus: Built-In 07:15:53 INFO - VRAM (Dynamic, Max): 1536 MB 07:15:53 INFO - Vendor: Intel (0x8086) 07:15:53 INFO - Device ID: 0x0a2e 07:15:53 INFO - Revision ID: 0x0009 07:15:53 INFO - Displays: 07:15:53 INFO - Display: 07:15:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:15:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:15:53 INFO - Display Serial Number: 1600x1200 60 07:15:53 INFO - Main Display: Yes 07:15:53 INFO - Mirror: Off 07:15:53 INFO - Online: Yes 07:15:53 INFO - Rotation: Supported 07:15:53 INFO - Return code: 0 07:15:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:15:53 INFO - Python 2.7.3 07:15:53 INFO - Return code: 0 07:15:53 INFO - grabbing minidump binary from tooltool 07:15: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')]} 07:15:53 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 0x10d412d30>, '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 0x10d236db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d409b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:15:53 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 07:15:53 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 07:15:53 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 07:15:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:15:53 INFO - Return code: 0 07:15:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:15:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:15:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:15:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:15:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:15:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:15:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:15:54 INFO - Return code: 0 07:15:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 07:15:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 07:15:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8qid9oRzpB/Render', 07:15:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:54 INFO - 'HOME': '/Users/cltbld', 07:15:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:54 INFO - 'LOGNAME': 'cltbld', 07:15:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:15:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:15:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:54 INFO - 'MOZ_NO_REMOTE': '1', 07:15:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:15:54 INFO - 'NO_EM_RESTART': '1', 07:15:54 INFO - 'PAGER': '/bin/cat', 07:15:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:54 INFO - 'PWD': '/builds/slave/test', 07:15:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:15:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:54 INFO - 'SHELL': '/bin/bash', 07:15:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cqweLfF1om/Listeners', 07:15:54 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 07:15:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:54 INFO - 'USER': 'cltbld', 07:15:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:54 INFO - 'XPC_FLAGS': '0x0', 07:15:54 INFO - 'XPC_SERVICE_NAME': '0', 07:15:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 07:15:54 INFO - mozversion application_buildid: 20160524051813 07:15:54 INFO - mozversion application_changeset: 74fe05a5f015f517f835f0b560b074a941103481 07:15:54 INFO - mozversion application_display_name: Nightly 07:15:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:15:54 INFO - mozversion application_name: Firefox 07:15:54 INFO - mozversion application_remotingname: firefox 07:15:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:15:54 INFO - mozversion application_vendor: Mozilla 07:15:54 INFO - mozversion application_version: 49.0a1 07:15:54 INFO - mozversion platform_buildid: 20160524051813 07:15:54 INFO - mozversion platform_changeset: 74fe05a5f015f517f835f0b560b074a941103481 07:15:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:15:54 INFO - mozversion platform_version: 49.0a1 07:15:54 INFO - using testdate: 1464099354 07:15:54 INFO - actual date: 1464099354 07:15:54 INFO - starting webserver on 'localhost:53050' 07:15:54 INFO - SUITE-START | Running 2 tests 07:15:54 INFO - TEST-START | dromaeo_css 07:15:54 INFO - operating with platform_type : mac_ 07:15:54 INFO - Initialising browser for dromaeo_css test... 07:15:54 INFO - TEST-INFO | started process 2197 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpRaan3f/profile http://localhost:53050/getInfo.html) 07:15:57 INFO - PROCESS | 2197 | __metrics Screen width/height:1600/1200 07:15:57 INFO - PROCESS | 2197 | colorDepth:24 07:15:57 INFO - PROCESS | 2197 | Browser inner width/height: 1024/689 07:15:57 INFO - PROCESS | 2197 | __metrics 07:15:57 ERROR - PROCESS | 2197 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 07:15:58 INFO - TEST-INFO | 2197: exit 0 07:15:58 INFO - Browser initialized. 07:15:58 INFO - Running cycle 1/1 for dromaeo_css test... 07:15:58 INFO - TEST-INFO | started process 2200 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpRaan3f/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:16:10 INFO - PROCESS | 2200 | RSS: Main: 249942016 07:16:10 INFO - PROCESS | 2200 | 07:17:00 INFO - PROCESS | 2200 | 1464099420201 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] 07:19:10 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:53050/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53050/tests/dromaeo/cssquery-ext.html) 07:19:12 INFO - PROCESS | 2200 | RSS: Main: 261414912 07:19:12 INFO - PROCESS | 2200 | 07:22:14 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:53050/tests/dromaeo/cssquery-ext.html (next: http://localhost:53050/tests/dromaeo/cssquery-jquery.html) 07:22:15 INFO - PROCESS | 2200 | RSS: Main: 273514496 07:22:15 INFO - PROCESS | 2200 | 07:25:16 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:53050/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53050/tests/dromaeo/cssquery-mootools.html) 07:25:17 INFO - PROCESS | 2200 | RSS: Main: 275456000 07:25:17 INFO - PROCESS | 2200 | 07:28:19 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:53050/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53050/tests/dromaeo/cssquery-prototype.html) 07:28:19 INFO - PROCESS | 2200 | RSS: Main: 277250048 07:28:19 INFO - PROCESS | 2200 | 07:31:22 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:53050/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53050/tests/dromaeo/cssquery-yui.html) 07:31:23 INFO - PROCESS | 2200 | RSS: Main: 280117248 07:31:23 INFO - PROCESS | 2200 | 07:34:25 INFO - PROCESS | 2200 | Cycle 1(1): loaded http://localhost:53050/tests/dromaeo/cssquery-yui.html (next: http://localhost:53050/tests/dromaeo/cssquery-dojo.html) 07:34:25 INFO - PROCESS | 2200 | RSS: Main: 281063424 07:34:25 INFO - PROCESS | 2200 | 07:34:25 INFO - PROCESS | 2200 | __start_tp_report 07:34:25 INFO - PROCESS | 2200 | _x_x_mozilla_page_load 07:34:25 INFO - PROCESS | 2200 | _x_x_mozilla_page_load_details 07:34:25 INFO - PROCESS | 2200 | |i|pagename|runs| 07:34:25 INFO - PROCESS | 2200 | |0;dojo.html;3474.148833535784;3478.1478537758253;3493.7379696522758;3531.152523394386;3551.644835516449;5600.103983362663;5695.74369153388;5727.255456790612;5823.505002074829;5830.941690583093;5638.492535671789;5731.168980497825;5740.7129643517765;5749.0514065179195;5830.183774271605;5162.716050617215;5211;5316.627836051478;5333.120035194188;5355.919661205085;759.2799425296398;4840.744097394177;5161.7161056141895;5202.3497062867145;5258.237555554442;5256.737163141846;5264.578833693296;5268.8155914542995;5277.8416647500635;5293;5257.579393648499;5300.310959575269;5308;5309.5752339812725;5341.278927344798;4878.8292409765845;5137.12668846297;5227.954409118153;5257.13257312543;5322.680639161624;5389.164679474688;5647.604667673265;5655;5660.405657405954;5681.545476361881;5200.167973124319;6112.138751317739;6236.812895613139;6278.811635650892;6315.052742088677;6199.628022318675;6231.12764213006;6299.937000630027;6304.590201636925;6305;5872.236609240814;5878.08889622105;5878.61788983719;5904.675242861684;5916.230889984317;6254.124422580842;6254.687265636791;6295.496360291166;6337.2395312562785;6342.334054924167;6193.504519638418;6231.189945307081;6231.532635052389;6233.719482623293;6248.218972628421;6187.752489900354;6301.96849015752;6318.304986451487;6331.5251356147555;6334.904976425358;6141.907871381933;6179.783707570214;6185.072239164071;6286;6288.968555157287;6234.1583886174785;6278.340774218641;6303.180586523814;6304.3695630437505;6306.148669929593;4353.717008394444;4976.4525902150735;5019.799208031711;5022.995400919831;5044.2937988681615;4417.028253784162;4429.53489883564;4460.420145381079;4531.478879928784;4542.818287268472;5619.887602247933;5643.322801263875;5700.40145784695;5713.857153571194;5725.227094342211;6245.281792593819;6262.812115636538;6284.214473190851;6306.621602703853;6316.652584107918;5596.048671725816;5734.713264336767;5752.194692743019;5764.962306784735;5765.538756899438;5428.104362780097;5463.453654634584;5480.123180291214;5503.449655034545;5519.862003449865;5551.111822108443;5669.518090962232;6187.16473276111;6465.838354041092;6481.481481481376;5100.362454693163;6190.978488549339;6243.719032643633;6248.812535623939;6276;6449;6470.06184103293;6471.546991710535;6479.092926990319;6488.935110648833;6173.629582225081;6182.659953702409;6195.659238741912;6268;6270.435660790551;4571.971306456021;5943.940560594512;6103.85289575482;6126.540509461719;6243.125962365181;5447.891042179213;6075.787347442818;6132.5707200495535;6133.754649813957;6144;6034.336223015553;6115.6330620162935;6147.016477363601;6154.753809847556;6167.629942203482;5227.634065615523;5589.972050139723;5839.445252700986;5939.316978547522;5960.76156953717;6135.509159267337;6152.938470615416;6163.229596300463;6165.290991535851;6178.721957511832;5817.331006934088;6013.6692481913915;6027.728752206073;6052.848678783065;6055.7577696893395 07:34:25 INFO - PROCESS | 2200 | |1;ext.html;509.9495040217153;513.6712503997442;526.1292560811858;536.8979893820175;541.9349678038636;32240.032799016008;41317.7934110329;41401;41442.792786036065;41477.58522414771;39668.8099357019;39745.801270993674;40605;41116.76649700514;41193.58806411935;7956.801079973018;9703.17523010545;9705.41767493949;9720.368176068532;9721.17370023549;9539.09378609031;9578;9619.278554108434;9643.324967252294;9651.179649729782;9456.669016584421;9591.424514529115;9630.759231019245;9654.082862128087;9660.90339096607;9462.006489318592;9587.760305992337;9643.3731807433;9648.855267170999;9651.758706032335;9481.66814161501;9521.381110227881;9528.285378596564;9572.760680982961;9636.084571965723;39204;39348.40977385342;39718.80140599307;39749.40375894364;40236.99407514806;2558.2069558436942;3105.378924215166;3239.7526952123603;3358.5633867597057;3586.5337506124038;28308.43383132326;28868.27829304284;29332.70667293321;29347.706522934925;29359.412811743645;2356.3402247370764;2736.7263273672675;2743.6570428696414;2871.0238518903675;2936.3980384021224;284876;348042;351473;352006;352108;58867;58911.70544147252;59228;59318;59321.110183347286;35613.46579801305;35976.28047439037;36238.27523449569;36934.81532592374;61808.69095654493;16088.75866861998;16538.25577849;16539.50381488557;16555.917220414063;16676;12697.619071427873;12806.231626102464;12879.484820607258;12881.871181288254;12891.35543222835;19090.14094365757;19174.13716382766;19366.41900742952;19379.222657126586;19922.310488083924;6745.460363170934;7896;8055.275025247639;8095.595220238964;8116.107228204893;899.8830152080187;908.7228395339353;980.7204946590188;1005.3446186247091;1118.2116607791488;30664;31117;31667.89162379352;31824;32268.259818491435;1049.3441599000626;1066.1364294921138;1070.2133931560297;1085.685151306128;1097.9953342691508;14734.63163420923;14736.926315368355;14958.925205374122;15096.698066038618;15184;601957;610669;614750;618527;624315;54936;55125.44874551283;56619;57036;57505.71247143738;1426.1656930695451;1448.174540511919;1452.5279284232668;1474.0566037735643;1474.668199655069;28224.85887570631;28315.433691325645;28353.71646283593;28936.710632893402;29090.709092909645;49702.005959881324;49776;50670;51456;51540.74229628828;506811;534131;538721;543175;548650;24657;24750;24763.876180619704;25358.74641253564;25787.742122578533;42655.91752330107;43215.56784432241;43927.78036109799;44411.88970275769;44640.33039504475;36578.45132323071;37345.253094937405;37387.813060934524;37652.246955061295;38024.239515210094;35410.822945885106;35504.46743298904;36187.914362569165;36251.637483625884;36257.093572660895;35296;36097.63902360943;36298.4555231677;36874.8156259217;36899;25591.744082558936;30479.390412191187;32636.510452342758;33445.16387090342;33857.83071084727;21085.789142108384;21981.681099134104;23408.88295558511;23881.04475820948;23967.640485393084 07:34:25 INFO - PROCESS | 2200 | |2;jquery.html;324.34320500985507;330.44815158685003;378.4077918058239;397.84484051220045;408.263085131338;20093.698594521076;20214.292499762512;20881.68677469839;21459.785402145975;21482.677759833576;20208.191672333127;21091;21936.670949935724;22246.55506889861;22289.996950137283;5722.48354186141;5805.970970145154;5901.881962360751;5908.586398952075;6043.969780151115;5973.731182096816;5987.012142996422;6007.939920600782;6014.3985601439945;6015;5668.886622267552;5960;6015.278166620012;6018.638881667092;6025.698715064229;5674.546036317085;5994;6021.698915054251;6023.548233882454;6047.909281360783;5171.069207542679;5176.197689358156;5183.222516622499;5193.870153246161;5221.765020574083;26684.199474015615;26697.798599063088;27041;27152.456950860873;27298.727012729818;2103.7685854555984;2110.7783682713402;2126.2133010785956;2133.861299015559;2174.9088808779297;6999.650017499104;8205.835883282361;13992.440302387893;14588.854111458855;15129.394824207018;1773.796013458443;1786.5890845105698;1796.1172318428994;1843.0508288231572;1861.9162137703836;73200.6339968302;98291.50854245682;100725.99274007312;108944.36583451334;110342;10244.743881402876;10341.224408169417;10819.67540973772;11304.699959504598;11551.653450396501;14490.85509144916;14529.25648922115;14568;14569.198694071716;14606;7380.527523279654;8667.523286219192;8717;8933.865992010125;8977.685780997634;7304.269573042653;7835.317400821605;8733.388662793543;8825.646974121091;8872.024077351487;6238.875222495525;6410.423061924449;6551.6069035857045;6726.899096513556;7165.925111233357;1857.6160760233713;1909.255390397746;1921.903904804782;1925.778535468411;1961.8626696131419;850.987325083149;967.4437198610827;975.9121679048777;1009.5810238750983;1014.9594016239267;20617;22004.559908802054;22196.556068878534;22222.88888555547;22238.666420003712;836.8786525953702;971.9745668319849;972.3436680240951;980.0395612299986;989.8812142542942;9887;11151.944240278746;11879.406029698654;11893.464794084288;11904.702382440335;595961;601023;641449;660657;663393;90155.54922225478;122383;123830.38084809518;126968;129886.35056824655;990.8365119755304;1000.8298589239847;1024.6106479537877;1039.1167507618616;1041.953112109957;19125.90437047806;19131.521711957314;19433.8056619432;19480.512987175432;19533.12100955489;44014;49545;51123.74438127786;51199.74400127975;55827.441725582226;78815;82573.17426825655;82900.8549572531;84824;85702;6457.870842583216;6746.763863264762;7389.778306650809;7452;7689.231076892266;7617.542947423172;9938.751531211488;12211.26732396059;12288.447019883948;12524.561640342545;9104.453732776055;9766.902330976884;10712.89287107119;10781.51483183243;10851.294665846695;9430.056994300789;10358.326708763907;10481.633142839964;10581.735456613646;10716.678499645022;10569.682909512727;10872.945635271773;11001.284916480403;11049.226554141133;11209.43952802373;7384.335409813142;7396.523137714248;7514.924850751422;7527.623618819147;7557.924420755722;9085.318601104811;10836.945815270874;10856.837147442637;11010.284331518425;11021.504032318404 07:34:25 INFO - PROCESS | 2200 | |3;mootools.html;1571.3164773323606;1593.394510086167;1806.2684612731848;1909.7135429685554;1930.314738267914;8012;8479.957600211997;8597.742067737961;8970.73087807366;8985.550722463868;8646.524441155734;8756.03683594804;8863;8892.555372231394;8905.688300909469;2342.4963632818985;2350.048230466664;2352.741198468167;2386.832921695482;2388.665586817847;2338.4270853640883;2372.5847976604114;2376.192094687806;2407.4823912858733;2408.662787209792;2338.9649155262687;2364.0661938534267;2387.940301492459;2399.8320117591775;2404.290734233405;2335.532893421314;2346.2257455039794;2357.1160814694404;2363.5036642305136;2373.6202207646693;2328.511012687338;2334.1130370459286;2354.835161538693;2364.373441038109;2366.857988520694;7524.548527088392;8805;8817.691380801642;8902.376833621649;8907.777305567348;5267.973660131714;5359.946400535984;5430.104032834578;5433.049216387117;5436.836894893159;3379.881704140343;3494.091536200581;3523.5067090607336;3588.659077387644;3599.370110230699;1015.7968406318765;1016.7000734783256;1017.0744622393586;1033.188946676855;1048.9213309001855;11644.010259127985;11656.067514598812;11782.941085294518;11833;11834.763304733857;3780.6597406233573;4068.4914385701786;4527.886802829956;4596.011857450664;4619.284010978304;9261.953690231505;9284;9295.163435290857;9304.441733495876;9335.206507446943;5164.431912489623;5192.935448233103;5204.34945631796;5267.236250743621;5282.52457278839;4453.265211240174;4771.403574553181;4871.975640121776;4950.059488697136;4964.478729733326;3795.7153213509096;3825.2731980923536;3870.6516413522354;3878.4182372644327;3935.6064393560414;1173.9941300293444;1178.298912147271;1182.1016027818748;1183.2782002978176;1192.493190394079;899.8110396816742;908.5411867007251;910.0717268386209;933.178802653674;943.5556837050182;5720.1991721159075;5858.7110835616095;5910.320313163955;5969.074793406942;5976.641401515923;387.5736689641372;418.1928877266843;424.7663784918283;433.12076484736036;446.0922023681792;3856.267309211241;3933.881983540441;3961.9405708913814;4012.996750812297;4013.097053163015;8891.777705557284;10829.945850270698;10839.916008399256;10843.186760992958;10874.67375978722;10458.581656733797;10822.567097316176;10869.728256793485;10870.239083264254;10875.619353322596;7895.7236496722335;8155;8306.377021723394;8403.453775504573;8406.537640429835;5702.144678298288;5842.444967728059;5954.255718035246;5963.642181469126;5976.3127240366175;9454.621815127319;10012.299139060196;10019.448930308903;10035.799284014132;10036.49817509107;9769.755756106155;10077.19382449417;10122.190224782147;10134.391936483835;10224.130948869131;7440.888386674096;7441.144268409203;7518.210587888193;7539.170691224071;7593.658285377061;7801.2198780121535;7805.297523222937;7858.489198202098;7876.133625301327;7889.368850491832;7256.564606123649;7424.814379640552;7495.850082998419;7499.400047996038;7569.470137090571;7173;7255.347018768336;7286.708531658886;7359.558426494427;7362.484626076123;6863.691133898887;6915;6943.9652801735665;6948.756793512405;6950.65246737651;4727.101850648366;4843.636727245541;4949.777260023236;4956.504349565014;4964.106460837084;4170.22850772608;4878.170710979071;4913.533214344631;4926.753662316798;4930.285108659296 07:34:25 INFO - PROCESS | 2200 | |4;prototype.html;298.0477374787553;299.54917848637797;312.0995926751322;320.8347700934018;322.10294330290117;4984.859538490755;5148.871278218046;5285.603579731521;5538.363088244853;5577.302837145352;5271.446498117691;5434.864128396792;5464.808731694391;5521.3926468088575;5546.86132846679;3137.403893260285;3493.9301213975705;4021.5375231848275;4098.877033688979;4146.771927543984;3229.9677003229904;3962.9009274768073;4084.326086195774;4153.107081977374;4161.500619925613;3751.212245428463;4118.608732170454;4127.525334586516;4131.6281534661875;4138.317177665682;3317.303366293081;3381.9830900845336;3533.187366905613;4066.064805094845;4088.632023117904;2891.2049186473887;3101.984490077535;3609.4224924012296;3615.927681446383;3624.151682641845;4416.757078360721;4886.51134886512;5141.280220769096;5158.561522270612;5192.454792246797;4662.7202367858035;4752.405949256343;4770.833020844254;4771.928421073687;4802.183628783116;4733.360996265529;5055.671381360236;5539.418361072069;5605.831825045255;5712;3462.3940810358085;3764.96691786435;3857.8010533995634;3870.78710670913;3994.6404823565726;4918.8278410255825;5091.745412729349;5523.199136125239;5737.062140684413;6025.879482410371;3377.915552111217;3994;4032.6169013944213;4043.5147782265726;4059.6346328830546;4087.305158123126;4122.6083522065355;4127;4127.525334586501;4135.110951145458;3759.736818422739;4500.932486012713;4741.454732705714;4742.644301677388;4770.403699537558;5480.287562616834;5709.714514274353;5735.541156707537;5801.825945221565;5877.5591830612875;1197.6886009637558;1202.20053664314;1260.5147018397984;1286.7812471879804;1426.6233085072954;832.3133414932583;854.5171977832505;873.733511279066;878.9648414063494;880.7533890510667;5205.022091605166;5331.86670333237;5355.491228333302;5401.513863752281;5409.594280428983;5576.7211639417865;5684.261046063985;5914.427224157505;5998.820035398945;6020.548458865603;4564.794584243717;4790.760461976887;4913.729744864067;5023.764424830988;5540.418256083134;2888.321244507539;3141.607299087614;3174.587308647676;3179.5374127900964;3185.665505121975;4998.800047998112;6248.656323902138;6256.249250090018;6343.492520598341;6425.626546063354;5009.474005229471;5371.75827087782;5651.491365777099;6241.375862413723;6263.467605253513;4867.805287788448;5285.947140528546;6169.475594574512;6269.655168965751;6343.841403964845;5720.570957178312;5996.400359963969;6003.369646187088;6063.181470501602;6066.4540191382985;4766.880827979328;4950.381202349707;5745.9425405745405;5931.258592675916;5969.373215812451;4860.198067318995;5503.862403439786;5796.174205125372;6154.661493617894;6314.62112273265;3769.4716397796055;3963.781991990468;3969.5633480316565;3979.6020397959974;3983.2830090583975;5730.885382292247;5978.701064946648;5992.610480318765;6051.243594550681;6061.181740464981;5114.874727559931;5522.86192845166;5557.166135175302;5912.167228159113;5943.227380440515;5588.608797384307;5768.221290125779;5827.621204621686;5839.357670656309;5855.882882342415;5384.380796208487;5709.714514274353;5726.112452569859;5727.427257274239;5731.790197489419;4984.984084628893;5381.165919282517;5671.234383358194;5813.476787089182;5853.619514731528;5744.597878148489;5745.109508026263;5748.223989761329;5778.219940308173;5840.8247752567495 07:34:25 INFO - PROCESS | 2200 | |5;yui.html;578.7482445136365;646.2729429391934;712.6187489693016;716.1370548489072;717.4762423430899;12122;15080.698386032273;15164.77252841207;15165.848341516608;15169.165695886735;14165.78751318731;14925.92537037316;15464.84535154651;15493.288674481948;15856.84143158568;920.3373571028882;923.7182659288915;931.5575101826646;931.5621657820814;933.733885842535;733.6478490324647;922.1562270522497;956.6795123633591;959.2757468111566;959.4003747657714;986.9703908882746;990.5592011554861;990.6730778842965;992.5731935267831;995.8854731705837;949.55370975641;954.1222075690381;979.2459805949414;981.4111533080167;986.5856340337076;691.7371398868448;731.1445608637906;738.479372042714;738.8152961759561;739.5156172706886;14742.631434214123;14837.851621483755;15083.321250543751;15170.544883653509;15175.848241517553;12477.75044499105;12562.497500099986;12620.936895315557;12810.10329276951;12931.48274069036;9921.355111917703;10015.198784097327;10027.59889604415;10257.281990260684;10321.58713651453;3526.3828829954655;3551.5027896094566;3562.928741425157;3641.799701016443;3645.908852278688;69331;77745.22254777436;78409.60795196044;79468.60265698693;79780;9681.854772178423;9682.75793105178;9686.176674982706;9737;9750.122488976027;22273.774942378324;22290.99690513931;22324.995375208153;22413.66379504309;22637.773622263896;7269.491135620561;7537.2462753724185;7743.264389882952;7847.529148251123;7988.600569971478;4032.1330913853662;4059.939100913489;4077.4087757275624;4079.469668943078;4081.428599996003;3455.1189446691437;3457.792532448061;3490.2495963367996;3525.153963048901;3554.182538016271;626.7211091064432;634.6826586706646;638.7093872288098;647.4270270810367;648.5103746671233;535.2132365422822;561.668615516847;587.6953163975967;588.9941100589025;594.625386006813;8095;8149.633266503021;8151.1441298662785;8242.258196762321;8322.916770832335;582.5718097198555;585.7949717598806;605.7365046204915;611.461913516103;619.6220305613572;3624.5650521937537;3762.529683789526;3853.845846166122;3917.177392747498;3936.252112098692;83288.58355708304;86183;86767.56616217004;88318;88320;73915;74680.62659686667;75360.62319688476;75654.24345756472;75905;25705.871470642527;25817.483650327264;41945.95135121646;63530.68234658797;64249.35750642527;7634.847303053797;7664.770056898302;7687.884681729666;7710.575918324546;7723.918651388698;40746.1850762989;41780.791096044326;43104.56895431005;43222.351664724425;43528;54680.72659636677;54839.45160548343;56468.717656411456;58965.410345895994;59498;10434.37393756377;10444.895551044696;10499;10637.521311541153;11032.613858514913;9885.85171222418;10995.120390368911;11076.092747215374;11215.485909402127;11234.213605047567;9737.269704772028;10231.641892533726;10464.058234758908;10475.895241047798;10736.087432568007;10253.282270241012;10268.281220314806;10361.274710770174;10522.473876306001;10803.675889723321;10023.248256380946;10070.798584028425;10244.692659220234;10252.487375631039;10297.588096476356;8776.078511756174;8811.030786613595;8840.381173317806;8854.822903542023;8963.417377870417;8636.093210212837;8873.866891996497;8996.730098097067;9019.233365164035;9040.003182749644 07:34:25 INFO - PROCESS | 2200 | __end_tp_report 07:34:25 INFO - PROCESS | 2200 | __start_cc_report 07:34:25 INFO - PROCESS | 2200 | _x_x_mozilla_cycle_collect,420 07:34:25 INFO - PROCESS | 2200 | __end_cc_report 07:34:25 INFO - PROCESS | 2200 | __startTimestamp1464100465983__endTimestamp 07:34:25 INFO - PROCESS | 2200 | 07:34:25 INFO - PROCESS | 2200 | ------- Summary: start ------- 07:34:25 INFO - PROCESS | 2200 | Number of tests: 6 07:34:25 INFO - PROCESS | 2200 | 07:34:25 INFO - PROCESS | 2200 | [#0] dojo.html Cycles:165 Average:5709.83 Median:5904.68 stddev:747.43 (12.7%) stddev-sans-first:728.85 07:34:26 INFO - PROCESS | 2200 | Values: 3474.1 3478.1 3493.7 3531.2 3551.6 5600.1 5695.7 5727.3 5823.5 5830.9 5638.5 5731.2 5740.7 5749.1 5830.2 5162.7 5211.0 5316.6 5333.1 5355.9 759.3 4840.7 5161.7 5202.3 5258.2 5256.7 5264.6 5268.8 5277.8 5293.0 5257.6 5300.3 5308.0 5309.6 5341.3 4878.8 5137.1 5228.0 5257.1 5322.7 5389.2 5647.6 5655.0 5660.4 5681.5 5200.2 6112.1 6236.8 6278.8 6315.1 6199.6 6231.1 6299.9 6304.6 6305.0 5872.2 5878.1 5878.6 5904.7 5916.2 6254.1 6254.7 6295.5 6337.2 6342.3 6193.5 6231.2 6231.5 6233.7 6248.2 6187.8 6302.0 6318.3 6331.5 6334.9 6141.9 6179.8 6185.1 6286.0 6289.0 6234.2 6278.3 6303.2 6304.4 6306.1 4353.7 4976.5 5019.8 5023.0 5044.3 4417.0 4429.5 4460.4 4531.5 4542.8 5619.9 5643.3 5700.4 5713.9 5725.2 6245.3 6262.8 6284.2 6306.6 6316.7 5596.0 5734.7 5752.2 5765.0 5765.5 5428.1 5463.5 5480.1 5503.4 5519.9 5551.1 5669.5 6187.2 6465.8 6481.5 5100.4 6191.0 6243.7 6248.8 6276.0 6449.0 6470.1 6471.5 6479.1 6488.9 6173.6 6182.7 6195.7 6268.0 6270.4 4572.0 5943.9 6103.9 6126.5 6243.1 5447.9 6075.8 6132.6 6133.8 6144.0 6034.3 6115.6 6147.0 6154.8 6167.6 5227.6 5590.0 5839.4 5939.3 5960.8 6135.5 6152.9 6163.2 6165.3 6178.7 5817.3 6013.7 6027.7 6052.8 6055.8 07:34:26 INFO - PROCESS | 2200 | 07:34:26 INFO - PROCESS | 2200 | [#1] ext.html Cycles:180 Average:62882.57 Median:36886.91 stddev:136116.92 (369.0%) stddev-sans-first:136418.20 07:34:26 INFO - PROCESS | 2200 | Values: 509.9 513.7 526.1 536.9 541.9 32240.0 41317.8 41401.0 41442.8 41477.6 39668.8 39745.8 40605.0 41116.8 41193.6 7956.8 9703.2 9705.4 9720.4 9721.2 9539.1 9578.0 9619.3 9643.3 9651.2 9456.7 9591.4 9630.8 9654.1 9660.9 9462.0 9587.8 9643.4 9648.9 9651.8 9481.7 9521.4 9528.3 9572.8 9636.1 39204.0 39348.4 39718.8 39749.4 40237.0 2558.2 3105.4 3239.8 3358.6 3586.5 28308.4 28868.3 29332.7 29347.7 29359.4 2356.3 2736.7 2743.7 2871.0 2936.4 284876.0 348042.0 351473.0 352006.0 352108.0 58867.0 58911.7 59228.0 59318.0 59321.1 35613.5 35976.3 36238.3 36934.8 61808.7 16088.8 16538.3 16539.5 16555.9 16676.0 12697.6 12806.2 12879.5 12881.9 12891.4 19090.1 19174.1 19366.4 19379.2 19922.3 6745.5 7896.0 8055.3 8095.6 8116.1 899.9 908.7 980.7 1005.3 1118.2 30664.0 31117.0 31667.9 31824.0 32268.3 1049.3 1066.1 1070.2 1085.7 1098.0 14734.6 14736.9 14958.9 15096.7 15184.0 601957.0 610669.0 614750.0 618527.0 624315.0 54936.0 55125.4 56619.0 57036.0 57505.7 1426.2 1448.2 1452.5 1474.1 1474.7 28224.9 28315.4 28353.7 28936.7 29090.7 49702.0 49776.0 50670.0 51456.0 51540.7 506811.0 534131.0 538721.0 543175.0 548650.0 24657.0 24750.0 24763.9 25358.7 25787.7 42655.9 43215.6 43927.8 44411.9 44640.3 36578.5 37345.3 37387.8 37652.2 38024.2 35410.8 35504.5 36187.9 36251.6 36257.1 35296.0 36097.6 36298.5 36874.8 36899.0 25591.7 30479.4 32636.5 33445.2 33857.8 21085.8 21981.7 23408.9 23881.0 23967.6 07:34:26 INFO - PROCESS | 2200 | 07:34:26 INFO - PROCESS | 2200 | [#2] jquery.html Cycles:180 Average:35305.92 Median:354.43 stddev:104868.35 (29588.1%) stddev-sans-first:105129.63 07:34:26 INFO - PROCESS | 2200 | Values: 324.3 330.4 378.4 397.8 408.3 20093.7 20214.3 20881.7 21459.8 21482.7 20208.2 21091.0 21936.7 22246.6 22290.0 5722.5 5806.0 5901.9 5908.6 6044.0 5973.7 5987.0 6007.9 6014.4 6015.0 5668.9 5960.0 6015.3 6018.6 6025.7 5674.5 5994.0 6021.7 6023.5 6047.9 5171.1 5176.2 5183.2 5193.9 5221.8 26684.2 26697.8 27041.0 27152.5 27298.7 2103.8 2110.8 2126.2 2133.9 2174.9 6999.7 8205.8 13992.4 14588.9 15129.4 1773.8 1786.6 1796.1 1843.1 1861.9 73200.6 98291.5 100726.0 108944.4 110342.0 10244.7 10341.2 10819.7 11304.7 11551.7 14490.9 14529.3 14568.0 14569.2 14606.0 7380.5 8667.5 8717.0 8933.9 8977.7 7304.3 7835.3 8733.4 8825.6 8872.0 6238.9 6410.4 6551.6 6726.9 7165.9 1857.6 1909.3 1921.9 1925.8 1961.9 851.0 967.4 975.9 1009.6 1015.0 20617.0 22004.6 22196.6 22222.9 22238.7 836.9 972.0 972.3 980.0 989.9 9887.0 11151.9 11879.4 11893.5 11904.7 595961.0 601023.0 641449.0 660657.0 663393.0 90155.5 122383.0 123830.4 126968.0 129886.4 990.8 1000.8 1024.6 1039.1 1042.0 19125.9 19131.5 19433.8 19480.5 19533.1 44014.0 49545.0 51123.7 51199.7 55827.4 78815.0 82573.2 82900.9 84824.0 85702.0 6457.9 6746.8 7389.8 7452.0 7689.2 7617.5 9938.8 12211.3 12288.4 12524.6 9104.5 9766.9 10712.9 10781.5 10851.3 9430.1 10358.3 10481.6 10581.7 10716.7 10569.7 10872.9 11001.3 11049.2 11209.4 7384.3 7396.5 7514.9 7527.6 7557.9 9085.3 10836.9 10856.8 11010.3 11021.5 07:34:26 INFO - PROCESS | 2200 | 07:34:26 INFO - PROCESS | 2200 | [#3] mootools.html Cycles:180 Average:5643.03 Median:4807.52 stddev:3188.36 (66.3%) stddev-sans-first:3182.62 07:34:26 INFO - PROCESS | 2200 | Values: 1571.3 1593.4 1806.3 1909.7 1930.3 8012.0 8480.0 8597.7 8970.7 8985.6 8646.5 8756.0 8863.0 8892.6 8905.7 2342.5 2350.0 2352.7 2386.8 2388.7 2338.4 2372.6 2376.2 2407.5 2408.7 2339.0 2364.1 2387.9 2399.8 2404.3 2335.5 2346.2 2357.1 2363.5 2373.6 2328.5 2334.1 2354.8 2364.4 2366.9 7524.5 8805.0 8817.7 8902.4 8907.8 5268.0 5359.9 5430.1 5433.0 5436.8 3379.9 3494.1 3523.5 3588.7 3599.4 1015.8 1016.7 1017.1 1033.2 1048.9 11644.0 11656.1 11782.9 11833.0 11834.8 3780.7 4068.5 4527.9 4596.0 4619.3 9262.0 9284.0 9295.2 9304.4 9335.2 5164.4 5192.9 5204.3 5267.2 5282.5 4453.3 4771.4 4872.0 4950.1 4964.5 3795.7 3825.3 3870.7 3878.4 3935.6 1174.0 1178.3 1182.1 1183.3 1192.5 899.8 908.5 910.1 933.2 943.6 5720.2 5858.7 5910.3 5969.1 5976.6 387.6 418.2 424.8 433.1 446.1 3856.3 3933.9 3961.9 4013.0 4013.1 8891.8 10829.9 10839.9 10843.2 10874.7 10458.6 10822.6 10869.7 10870.2 10875.6 7895.7 8155.0 8306.4 8403.5 8406.5 5702.1 5842.4 5954.3 5963.6 5976.3 9454.6 10012.3 10019.4 10035.8 10036.5 9769.8 10077.2 10122.2 10134.4 10224.1 7440.9 7441.1 7518.2 7539.2 7593.7 7801.2 7805.3 7858.5 7876.1 7889.4 7256.6 7424.8 7495.9 7499.4 7569.5 7173.0 7255.3 7286.7 7359.6 7362.5 6863.7 6915.0 6944.0 6948.8 6950.7 4727.1 4843.6 4949.8 4956.5 4964.1 4170.2 4878.2 4913.5 4926.8 4930.3 07:34:26 INFO - PROCESS | 2200 | 07:34:26 INFO - PROCESS | 2200 | [#4] prototype.html Cycles:180 Average:4632.38 Median:5153.72 stddev:1463.62 (28.4%) stddev-sans-first:1431.11 07:34:26 INFO - PROCESS | 2200 | Values: 298.0 299.5 312.1 320.8 322.1 4984.9 5148.9 5285.6 5538.4 5577.3 5271.4 5434.9 5464.8 5521.4 5546.9 3137.4 3493.9 4021.5 4098.9 4146.8 3230.0 3962.9 4084.3 4153.1 4161.5 3751.2 4118.6 4127.5 4131.6 4138.3 3317.3 3382.0 3533.2 4066.1 4088.6 2891.2 3102.0 3609.4 3615.9 3624.2 4416.8 4886.5 5141.3 5158.6 5192.5 4662.7 4752.4 4770.8 4771.9 4802.2 4733.4 5055.7 5539.4 5605.8 5712.0 3462.4 3765.0 3857.8 3870.8 3994.6 4918.8 5091.7 5523.2 5737.1 6025.9 3377.9 3994.0 4032.6 4043.5 4059.6 4087.3 4122.6 4127.0 4127.5 4135.1 3759.7 4500.9 4741.5 4742.6 4770.4 5480.3 5709.7 5735.5 5801.8 5877.6 1197.7 1202.2 1260.5 1286.8 1426.6 832.3 854.5 873.7 879.0 880.8 5205.0 5331.9 5355.5 5401.5 5409.6 5576.7 5684.3 5914.4 5998.8 6020.5 4564.8 4790.8 4913.7 5023.8 5540.4 2888.3 3141.6 3174.6 3179.5 3185.7 4998.8 6248.7 6256.2 6343.5 6425.6 5009.5 5371.8 5651.5 6241.4 6263.5 4867.8 5285.9 6169.5 6269.7 6343.8 5720.6 5996.4 6003.4 6063.2 6066.5 4766.9 4950.4 5745.9 5931.3 5969.4 4860.2 5503.9 5796.2 6154.7 6314.6 3769.5 3963.8 3969.6 3979.6 3983.3 5730.9 5978.7 5992.6 6051.2 6061.2 5114.9 5522.9 5557.2 5912.2 5943.2 5588.6 5768.2 5827.6 5839.4 5855.9 5384.4 5709.7 5726.1 5727.4 5731.8 4985.0 5381.2 5671.2 5813.5 5853.6 5744.6 5745.1 5748.2 5778.2 5840.8 07:34:26 INFO - PROCESS | 2200 | 07:34:26 INFO - PROCESS | 2200 | [#5] yui.html Cycles:180 Average:16599.61 Median:29780.02 stddev:22998.03 (77.2%) stddev-sans-first:23031.08 07:34:26 INFO - PROCESS | 2200 | Values: 578.7 646.3 712.6 716.1 717.5 12122.0 15080.7 15164.8 15165.8 15169.2 14165.8 14925.9 15464.8 15493.3 15856.8 920.3 923.7 931.6 931.6 933.7 733.6 922.2 956.7 959.3 959.4 987.0 990.6 990.7 992.6 995.9 949.6 954.1 979.2 981.4 986.6 691.7 731.1 738.5 738.8 739.5 14742.6 14837.9 15083.3 15170.5 15175.8 12477.8 12562.5 12620.9 12810.1 12931.5 9921.4 10015.2 10027.6 10257.3 10321.6 3526.4 3551.5 3562.9 3641.8 3645.9 69331.0 77745.2 78409.6 79468.6 79780.0 9681.9 9682.8 9686.2 9737.0 9750.1 22273.8 22291.0 22325.0 22413.7 22637.8 7269.5 7537.2 7743.3 7847.5 7988.6 4032.1 4059.9 4077.4 4079.5 4081.4 3455.1 3457.8 3490.2 3525.2 3554.2 626.7 634.7 638.7 647.4 648.5 535.2 561.7 587.7 589.0 594.6 8095.0 8149.6 8151.1 8242.3 8322.9 582.6 585.8 605.7 611.5 619.6 3624.6 3762.5 3853.8 3917.2 3936.3 83288.6 86183.0 86767.6 88318.0 88320.0 73915.0 74680.6 75360.6 75654.2 75905.0 25705.9 25817.5 41946.0 63530.7 64249.4 7634.8 7664.8 7687.9 7710.6 7723.9 40746.2 41780.8 43104.6 43222.4 43528.0 54680.7 54839.5 56468.7 58965.4 59498.0 10434.4 10444.9 10499.0 10637.5 11032.6 9885.9 10995.1 11076.1 11215.5 11234.2 9737.3 10231.6 10464.1 10475.9 10736.1 10253.3 10268.3 10361.3 10522.5 10803.7 10023.2 10070.8 10244.7 10252.5 10297.6 8776.1 8811.0 8840.4 8854.8 8963.4 8636.1 8873.9 8996.7 9019.2 9040.0 07:34:26 INFO - PROCESS | 2200 | -------- Summary: end -------- 07:34:26 INFO - PROCESS | 2200 | 07:34:26 INFO - TEST-INFO | 2200: exit 0 07:34:26 INFO - TEST-OK | dromaeo_css | took 1111659ms 07:34:26 INFO - TEST-START | kraken 07:34:26 INFO - operating with platform_type : mac_ 07:34:26 INFO - Initialising browser for kraken test... 07:34:26 INFO - TEST-INFO | started process 2206 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpMGjTIc/profile http://localhost:53050/getInfo.html) 07:34:29 INFO - PROCESS | 2206 | __metrics Screen width/height:1600/1200 07:34:29 INFO - PROCESS | 2206 | colorDepth:24 07:34:29 INFO - PROCESS | 2206 | Browser inner width/height: 1024/689 07:34:29 INFO - PROCESS | 2206 | __metrics 07:34:29 ERROR - PROCESS | 2206 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 07:34:30 INFO - TEST-INFO | 2206: exit 0 07:34:30 INFO - Browser initialized. 07:34:30 INFO - Running cycle 1/1 for kraken test... 07:34:30 INFO - TEST-INFO | started process 2209 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpMGjTIc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:34:42 INFO - PROCESS | 2209 | RSS: Main: 249372672 07:34:42 INFO - PROCESS | 2209 | 07:34:45 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53050/tests/kraken/driver.html?testName=audio-beat-detection) 07:34:46 INFO - PROCESS | 2209 | RSS: Main: 261308416 07:34:46 INFO - PROCESS | 2209 | 07:34:49 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53050/tests/kraken/driver.html?testName=audio-dft) 07:34:49 INFO - PROCESS | 2209 | RSS: Main: 266563584 07:34:49 INFO - PROCESS | 2209 | 07:34:53 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53050/tests/kraken/driver.html?testName=audio-fft) 07:34:53 INFO - PROCESS | 2209 | RSS: Main: 266858496 07:34:53 INFO - PROCESS | 2209 | 07:34:55 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53050/tests/kraken/driver.html?testName=audio-oscillator) 07:34:56 INFO - PROCESS | 2209 | RSS: Main: 267108352 07:34:56 INFO - PROCESS | 2209 | 07:34:58 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53050/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:34:59 INFO - PROCESS | 2209 | RSS: Main: 267681792 07:34:59 INFO - PROCESS | 2209 | 07:35:02 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53050/tests/kraken/driver.html?testName=imaging-darkroom) 07:35:03 INFO - PROCESS | 2209 | RSS: Main: 268226560 07:35:03 INFO - PROCESS | 2209 | 07:35:06 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53050/tests/kraken/driver.html?testName=imaging-desaturate) 07:35:07 INFO - PROCESS | 2209 | RSS: Main: 269008896 07:35:07 INFO - PROCESS | 2209 | 07:35:10 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53050/tests/kraken/driver.html?testName=json-parse-financial) 07:35:11 INFO - PROCESS | 2209 | RSS: Main: 266829824 07:35:11 INFO - PROCESS | 2209 | 07:35:13 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53050/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:35:13 INFO - PROCESS | 2209 | RSS: Main: 267247616 07:35:13 INFO - PROCESS | 2209 | 07:35:15 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:35:16 INFO - PROCESS | 2209 | RSS: Main: 267726848 07:35:16 INFO - PROCESS | 2209 | 07:35:18 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:35:19 INFO - PROCESS | 2209 | RSS: Main: 267583488 07:35:19 INFO - PROCESS | 2209 | 07:35:22 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:35:23 INFO - PROCESS | 2209 | RSS: Main: 267448320 07:35:23 INFO - PROCESS | 2209 | 07:35:26 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:35:27 INFO - PROCESS | 2209 | RSS: Main: 267579392 07:35:27 INFO - PROCESS | 2209 | 07:35:28 INFO - PROCESS | 2209 | Cycle 1(1): loaded http://localhost:53050/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53050/tests/kraken/driver.html?testName=ai-astar) 07:35:29 INFO - PROCESS | 2209 | RSS: Main: 270512128 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | __start_tp_report 07:35:29 INFO - PROCESS | 2209 | _x_x_mozilla_page_load 07:35:29 INFO - PROCESS | 2209 | _x_x_mozilla_page_load_details 07:35:29 INFO - PROCESS | 2209 | |i|pagename|runs| 07:35:29 INFO - PROCESS | 2209 | |0;ai-astar;127;154;161;116;116;116;116;116;115;114 07:35:29 INFO - PROCESS | 2209 | |1;audio-beat-detection;160;124;142;199;122;145;141;123;205;148 07:35:29 INFO - PROCESS | 2209 | |2;audio-dft;192;208;236;276;241;241;272;220;199;197 07:35:29 INFO - PROCESS | 2209 | |3;audio-fft;70;81;75;174;58;60;58;65;136;59 07:35:29 INFO - PROCESS | 2209 | |4;audio-oscillator;85;114;203;112;114;85;122;81;81;148 07:35:29 INFO - PROCESS | 2209 | |5;imaging-gaussian-blur;97;127;132;86;87;86;88;85;91;85 07:35:29 INFO - PROCESS | 2209 | |6;imaging-darkroom;111;148;107;94;94;148;132;95;95;105 07:35:29 INFO - PROCESS | 2209 | |7;imaging-desaturate;100;143;99;92;107;111;137;101;139;153 07:35:29 INFO - PROCESS | 2209 | |8;json-parse-financial;57;61;60;82;84;89;88;131;60;59 07:35:29 INFO - PROCESS | 2209 | |9;json-stringify-tinderbox;70;67;70;74;79;79;68;96;64;92 07:35:29 INFO - PROCESS | 2209 | |10;stanford-crypto-aes;103;76;88;99;113;106;80;110;110;108 07:35:29 INFO - PROCESS | 2209 | |11;stanford-crypto-ccm;139;136;139;136;132;140;147;139;206;153 07:35:29 INFO - PROCESS | 2209 | |12;stanford-crypto-pbkdf2;226;206;166;183;223;220;149;220;226;167 07:35:29 INFO - PROCESS | 2209 | |13;stanford-crypto-sha256-iterative;73;69;66;66;65;67;69;65;68;63 07:35:29 INFO - PROCESS | 2209 | __end_tp_report 07:35:29 INFO - PROCESS | 2209 | __start_cc_report 07:35:29 INFO - PROCESS | 2209 | _x_x_mozilla_cycle_collect,1091 07:35:29 INFO - PROCESS | 2209 | __end_cc_report 07:35:29 INFO - PROCESS | 2209 | __startTimestamp1464100529022__endTimestamp 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | ------- Summary: start ------- 07:35:29 INFO - PROCESS | 2209 | Number of tests: 14 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#0] ai-astar Cycles:10 Average:125.10 Median:116.00 stddev:17.53 (15.1%) stddev-sans-first:18.58 07:35:29 INFO - PROCESS | 2209 | Values: 127.0 154.0 161.0 116.0 116.0 116.0 116.0 116.0 115.0 114.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#1] audio-beat-detection Cycles:10 Average:150.90 Median:146.50 stddev:29.59 (20.2%) stddev-sans-first:31.20 07:35:29 INFO - PROCESS | 2209 | Values: 160.0 124.0 142.0 199.0 122.0 145.0 141.0 123.0 205.0 148.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#2] audio-dft Cycles:10 Average:228.20 Median:238.50 stddev:30.19 (12.7%) stddev-sans-first:29.04 07:35:29 INFO - PROCESS | 2209 | Values: 192.0 208.0 236.0 276.0 241.0 241.0 272.0 220.0 199.0 197.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#3] audio-fft Cycles:10 Average:83.60 Median:62.50 stddev:39.44 (63.1%) stddev-sans-first:41.53 07:35:29 INFO - PROCESS | 2209 | Values: 70.0 81.0 75.0 174.0 58.0 60.0 58.0 65.0 136.0 59.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#4] audio-oscillator Cycles:10 Average:114.50 Median:142.00 stddev:38.01 (26.8%) stddev-sans-first:38.79 07:35:29 INFO - PROCESS | 2209 | Values: 85.0 114.0 203.0 112.0 114.0 85.0 122.0 81.0 81.0 148.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:86.50 stddev:17.85 (20.6%) stddev-sans-first:18.93 07:35:29 INFO - PROCESS | 2209 | Values: 97.0 127.0 132.0 86.0 87.0 86.0 88.0 85.0 91.0 85.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#6] imaging-darkroom Cycles:10 Average:112.90 Median:121.00 stddev:21.82 (18.0%) stddev-sans-first:23.13 07:35:29 INFO - PROCESS | 2209 | Values: 111.0 148.0 107.0 94.0 94.0 148.0 132.0 95.0 95.0 105.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#7] imaging-desaturate Cycles:10 Average:118.20 Median:141.00 stddev:22.29 (15.8%) stddev-sans-first:22.65 07:35:29 INFO - PROCESS | 2209 | Values: 100.0 143.0 99.0 92.0 107.0 111.0 137.0 101.0 139.0 153.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#8] json-parse-financial Cycles:10 Average:77.10 Median:71.50 stddev:23.13 (32.3%) stddev-sans-first:23.36 07:35:29 INFO - PROCESS | 2209 | Values: 57.0 61.0 60.0 82.0 84.0 89.0 88.0 131.0 60.0 59.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#9] json-stringify-tinderbox Cycles:10 Average:75.90 Median:76.50 stddev:10.74 (14.0%) stddev-sans-first:11.18 07:35:29 INFO - PROCESS | 2209 | Values: 70.0 67.0 70.0 74.0 79.0 79.0 68.0 96.0 64.0 92.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#10] stanford-crypto-aes Cycles:10 Average:99.30 Median:94.50 stddev:13.31 (14.1%) stddev-sans-first:14.05 07:35:29 INFO - PROCESS | 2209 | Values: 103.0 76.0 88.0 99.0 113.0 106.0 80.0 110.0 110.0 108.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:139.50 stddev:21.66 (15.5%) stddev-sans-first:22.80 07:35:29 INFO - PROCESS | 2209 | Values: 139.0 136.0 139.0 136.0 132.0 140.0 147.0 139.0 206.0 153.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.60 Median:220.00 stddev:29.50 (13.4%) stddev-sans-first:29.58 07:35:29 INFO - PROCESS | 2209 | Values: 226.0 206.0 166.0 183.0 223.0 220.0 149.0 220.0 226.0 167.0 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - PROCESS | 2209 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:2.81 (4.2%) stddev-sans-first:2.01 07:35:29 INFO - PROCESS | 2209 | Values: 73.0 69.0 66.0 66.0 65.0 67.0 69.0 65.0 68.0 63.0 07:35:29 INFO - PROCESS | 2209 | -------- Summary: end -------- 07:35:29 INFO - PROCESS | 2209 | 07:35:29 INFO - TEST-INFO | 2209: exit 0 07:35:29 INFO - TEST-OK | kraken | took 63295ms 07:35:29 INFO - SUITE-END | took 1174s 07:35:30 INFO - Completed test suite (00:19:35) 07:35:30 INFO - javascript benchmark 07:35:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7091.626104325252, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3474.148833535784, 3478.1478537758253, 3493.7379696522758, 3531.152523394386, 3551.644835516449, 5600.103983362663, 5695.74369153388, 5727.255456790612, 5823.505002074829, 5830.941690583093, 5638.492535671789, 5731.168980497825, 5740.7129643517765, 5749.0514065179195, 5830.183774271605, 5162.716050617215, 5211.0, 5316.627836051478, 5333.120035194188, 5355.919661205085, 759.2799425296398, 4840.744097394177, 5161.7161056141895, 5202.3497062867145, 5258.237555554442, 5256.737163141846, 5264.578833693296, 5268.8155914542995, 5277.8416647500635, 5293.0, 5257.579393648499, 5300.310959575269, 5308.0, 5309.5752339812725, 5341.278927344798, 4878.8292409765845, 5137.12668846297, 5227.954409118153, 5257.13257312543, 5322.680639161624, 5389.164679474688, 5647.604667673265, 5655.0, 5660.405657405954, 5681.545476361881, 5200.167973124319, 6112.138751317739, 6236.812895613139, 6278.811635650892, 6315.052742088677, 6199.628022318675, 6231.12764213006, 6299.937000630027, 6304.590201636925, 6305.0, 5872.236609240814, 5878.08889622105, 5878.61788983719, 5904.675242861684, 5916.230889984317, 6254.124422580842, 6254.687265636791, 6295.496360291166, 6337.2395312562785, 6342.334054924167, 6193.504519638418, 6231.189945307081, 6231.532635052389, 6233.719482623293, 6248.218972628421, 6187.752489900354, 6301.96849015752, 6318.304986451487, 6331.5251356147555, 6334.904976425358, 6141.907871381933, 6179.783707570214, 6185.072239164071, 6286.0, 6288.968555157287, 6234.1583886174785, 6278.340774218641, 6303.180586523814, 6304.3695630437505, 6306.148669929593, 4353.717008394444, 4976.4525902150735, 5019.799208031711, 5022.995400919831, 5044.2937988681615, 4417.028253784162, 4429.53489883564, 4460.420145381079, 4531.478879928784, 4542.818287268472, 5619.887602247933, 5643.322801263875, 5700.40145784695, 5713.857153571194, 5725.227094342211, 6245.281792593819, 6262.812115636538, 6284.214473190851, 6306.621602703853, 6316.652584107918, 5596.048671725816, 5734.713264336767, 5752.194692743019, 5764.962306784735, 5765.538756899438, 5428.104362780097, 5463.453654634584, 5480.123180291214, 5503.449655034545, 5519.862003449865, 5551.111822108443, 5669.518090962232, 6187.16473276111, 6465.838354041092, 6481.481481481376, 5100.362454693163, 6190.978488549339, 6243.719032643633, 6248.812535623939, 6276.0, 6449.0, 6470.06184103293, 6471.546991710535, 6479.092926990319, 6488.935110648833, 6173.629582225081, 6182.659953702409, 6195.659238741912, 6268.0, 6270.435660790551, 4571.971306456021, 5943.940560594512, 6103.85289575482, 6126.540509461719, 6243.125962365181, 5447.891042179213, 6075.787347442818, 6132.5707200495535, 6133.754649813957, 6144.0, 6034.336223015553, 6115.6330620162935, 6147.016477363601, 6154.753809847556, 6167.629942203482, 5227.634065615523, 5589.972050139723, 5839.445252700986, 5939.316978547522, 5960.76156953717, 6135.509159267337, 6152.938470615416, 6163.229596300463, 6165.290991535851, 6178.721957511832, 5817.331006934088, 6013.6692481913915, 6027.728752206073, 6052.848678783065, 6055.7577696893395], "value": 5666.423100778083, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.9495040217153, 513.6712503997442, 526.1292560811858, 536.8979893820175, 541.9349678038636, 32240.032799016008, 41317.7934110329, 41401.0, 41442.792786036065, 41477.58522414771, 39668.8099357019, 39745.801270993674, 40605.0, 41116.76649700514, 41193.58806411935, 7956.801079973018, 9703.17523010545, 9705.41767493949, 9720.368176068532, 9721.17370023549, 9539.09378609031, 9578.0, 9619.278554108434, 9643.324967252294, 9651.179649729782, 9456.669016584421, 9591.424514529115, 9630.759231019245, 9654.082862128087, 9660.90339096607, 9462.006489318592, 9587.760305992337, 9643.3731807433, 9648.855267170999, 9651.758706032335, 9481.66814161501, 9521.381110227881, 9528.285378596564, 9572.760680982961, 9636.084571965723, 39204.0, 39348.40977385342, 39718.80140599307, 39749.40375894364, 40236.99407514806, 2558.2069558436942, 3105.378924215166, 3239.7526952123603, 3358.5633867597057, 3586.5337506124038, 28308.43383132326, 28868.27829304284, 29332.70667293321, 29347.706522934925, 29359.412811743645, 2356.3402247370764, 2736.7263273672675, 2743.6570428696414, 2871.0238518903675, 2936.3980384021224, 284876.0, 348042.0, 351473.0, 352006.0, 352108.0, 58867.0, 58911.70544147252, 59228.0, 59318.0, 59321.110183347286, 35613.46579801305, 35976.28047439037, 36238.27523449569, 36934.81532592374, 61808.69095654493, 16088.75866861998, 16538.25577849, 16539.50381488557, 16555.917220414063, 16676.0, 12697.619071427873, 12806.231626102464, 12879.484820607258, 12881.871181288254, 12891.35543222835, 19090.14094365757, 19174.13716382766, 19366.41900742952, 19379.222657126586, 19922.310488083924, 6745.460363170934, 7896.0, 8055.275025247639, 8095.595220238964, 8116.107228204893, 899.8830152080187, 908.7228395339353, 980.7204946590188, 1005.3446186247091, 1118.2116607791488, 30664.0, 31117.0, 31667.89162379352, 31824.0, 32268.259818491435, 1049.3441599000626, 1066.1364294921138, 1070.2133931560297, 1085.685151306128, 1097.9953342691508, 14734.63163420923, 14736.926315368355, 14958.925205374122, 15096.698066038618, 15184.0, 601957.0, 610669.0, 614750.0, 618527.0, 624315.0, 54936.0, 55125.44874551283, 56619.0, 57036.0, 57505.71247143738, 1426.1656930695451, 1448.174540511919, 1452.5279284232668, 1474.0566037735643, 1474.668199655069, 28224.85887570631, 28315.433691325645, 28353.71646283593, 28936.710632893402, 29090.709092909645, 49702.005959881324, 49776.0, 50670.0, 51456.0, 51540.74229628828, 506811.0, 534131.0, 538721.0, 543175.0, 548650.0, 24657.0, 24750.0, 24763.876180619704, 25358.74641253564, 25787.742122578533, 42655.91752330107, 43215.56784432241, 43927.78036109799, 44411.88970275769, 44640.33039504475, 36578.45132323071, 37345.253094937405, 37387.813060934524, 37652.246955061295, 38024.239515210094, 35410.822945885106, 35504.46743298904, 36187.914362569165, 36251.637483625884, 36257.093572660895, 35296.0, 36097.63902360943, 36298.4555231677, 36874.8156259217, 36899.0, 25591.744082558936, 30479.390412191187, 32636.510452342758, 33445.16387090342, 33857.83071084727, 21085.789142108384, 21981.681099134104, 23408.88295558511, 23881.04475820948, 23967.640485393084], "value": 19092.841216908855, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.34320500985507, 330.44815158685003, 378.4077918058239, 397.84484051220045, 408.263085131338, 20093.698594521076, 20214.292499762512, 20881.68677469839, 21459.785402145975, 21482.677759833576, 20208.191672333127, 21091.0, 21936.670949935724, 22246.55506889861, 22289.996950137283, 5722.48354186141, 5805.970970145154, 5901.881962360751, 5908.586398952075, 6043.969780151115, 5973.731182096816, 5987.012142996422, 6007.939920600782, 6014.3985601439945, 6015.0, 5668.886622267552, 5960.0, 6015.278166620012, 6018.638881667092, 6025.698715064229, 5674.546036317085, 5994.0, 6021.698915054251, 6023.548233882454, 6047.909281360783, 5171.069207542679, 5176.197689358156, 5183.222516622499, 5193.870153246161, 5221.765020574083, 26684.199474015615, 26697.798599063088, 27041.0, 27152.456950860873, 27298.727012729818, 2103.7685854555984, 2110.7783682713402, 2126.2133010785956, 2133.861299015559, 2174.9088808779297, 6999.650017499104, 8205.835883282361, 13992.440302387893, 14588.854111458855, 15129.394824207018, 1773.796013458443, 1786.5890845105698, 1796.1172318428994, 1843.0508288231572, 1861.9162137703836, 73200.6339968302, 98291.50854245682, 100725.99274007312, 108944.36583451334, 110342.0, 10244.743881402876, 10341.224408169417, 10819.67540973772, 11304.699959504598, 11551.653450396501, 14490.85509144916, 14529.25648922115, 14568.0, 14569.198694071716, 14606.0, 7380.527523279654, 8667.523286219192, 8717.0, 8933.865992010125, 8977.685780997634, 7304.269573042653, 7835.317400821605, 8733.388662793543, 8825.646974121091, 8872.024077351487, 6238.875222495525, 6410.423061924449, 6551.6069035857045, 6726.899096513556, 7165.925111233357, 1857.6160760233713, 1909.255390397746, 1921.903904804782, 1925.778535468411, 1961.8626696131419, 850.987325083149, 967.4437198610827, 975.9121679048777, 1009.5810238750983, 1014.9594016239267, 20617.0, 22004.559908802054, 22196.556068878534, 22222.88888555547, 22238.666420003712, 836.8786525953702, 971.9745668319849, 972.3436680240951, 980.0395612299986, 989.8812142542942, 9887.0, 11151.944240278746, 11879.406029698654, 11893.464794084288, 11904.702382440335, 595961.0, 601023.0, 641449.0, 660657.0, 663393.0, 90155.54922225478, 122383.0, 123830.38084809518, 126968.0, 129886.35056824655, 990.8365119755304, 1000.8298589239847, 1024.6106479537877, 1039.1167507618616, 1041.953112109957, 19125.90437047806, 19131.521711957314, 19433.8056619432, 19480.512987175432, 19533.12100955489, 44014.0, 49545.0, 51123.74438127786, 51199.74400127975, 55827.441725582226, 78815.0, 82573.17426825655, 82900.8549572531, 84824.0, 85702.0, 6457.870842583216, 6746.763863264762, 7389.778306650809, 7452.0, 7689.231076892266, 7617.542947423172, 9938.751531211488, 12211.26732396059, 12288.447019883948, 12524.561640342545, 9104.453732776055, 9766.902330976884, 10712.89287107119, 10781.51483183243, 10851.294665846695, 9430.056994300789, 10358.326708763907, 10481.633142839964, 10581.735456613646, 10716.678499645022, 10569.682909512727, 10872.945635271773, 11001.284916480403, 11049.226554141133, 11209.43952802373, 7384.335409813142, 7396.523137714248, 7514.924850751422, 7527.623618819147, 7557.924420755722, 9085.318601104811, 10836.945815270874, 10856.837147442637, 11010.284331518425, 11021.504032318404], "value": 9540.569634813646, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1571.3164773323606, 1593.394510086167, 1806.2684612731848, 1909.7135429685554, 1930.314738267914, 8012.0, 8479.957600211997, 8597.742067737961, 8970.73087807366, 8985.550722463868, 8646.524441155734, 8756.03683594804, 8863.0, 8892.555372231394, 8905.688300909469, 2342.4963632818985, 2350.048230466664, 2352.741198468167, 2386.832921695482, 2388.665586817847, 2338.4270853640883, 2372.5847976604114, 2376.192094687806, 2407.4823912858733, 2408.662787209792, 2338.9649155262687, 2364.0661938534267, 2387.940301492459, 2399.8320117591775, 2404.290734233405, 2335.532893421314, 2346.2257455039794, 2357.1160814694404, 2363.5036642305136, 2373.6202207646693, 2328.511012687338, 2334.1130370459286, 2354.835161538693, 2364.373441038109, 2366.857988520694, 7524.548527088392, 8805.0, 8817.691380801642, 8902.376833621649, 8907.777305567348, 5267.973660131714, 5359.946400535984, 5430.104032834578, 5433.049216387117, 5436.836894893159, 3379.881704140343, 3494.091536200581, 3523.5067090607336, 3588.659077387644, 3599.370110230699, 1015.7968406318765, 1016.7000734783256, 1017.0744622393586, 1033.188946676855, 1048.9213309001855, 11644.010259127985, 11656.067514598812, 11782.941085294518, 11833.0, 11834.763304733857, 3780.6597406233573, 4068.4914385701786, 4527.886802829956, 4596.011857450664, 4619.284010978304, 9261.953690231505, 9284.0, 9295.163435290857, 9304.441733495876, 9335.206507446943, 5164.431912489623, 5192.935448233103, 5204.34945631796, 5267.236250743621, 5282.52457278839, 4453.265211240174, 4771.403574553181, 4871.975640121776, 4950.059488697136, 4964.478729733326, 3795.7153213509096, 3825.2731980923536, 3870.6516413522354, 3878.4182372644327, 3935.6064393560414, 1173.9941300293444, 1178.298912147271, 1182.1016027818748, 1183.2782002978176, 1192.493190394079, 899.8110396816742, 908.5411867007251, 910.0717268386209, 933.178802653674, 943.5556837050182, 5720.1991721159075, 5858.7110835616095, 5910.320313163955, 5969.074793406942, 5976.641401515923, 387.5736689641372, 418.1928877266843, 424.7663784918283, 433.12076484736036, 446.0922023681792, 3856.267309211241, 3933.881983540441, 3961.9405708913814, 4012.996750812297, 4013.097053163015, 8891.777705557284, 10829.945850270698, 10839.916008399256, 10843.186760992958, 10874.67375978722, 10458.581656733797, 10822.567097316176, 10869.728256793485, 10870.239083264254, 10875.619353322596, 7895.7236496722335, 8155.0, 8306.377021723394, 8403.453775504573, 8406.537640429835, 5702.144678298288, 5842.444967728059, 5954.255718035246, 5963.642181469126, 5976.3127240366175, 9454.621815127319, 10012.299139060196, 10019.448930308903, 10035.799284014132, 10036.49817509107, 9769.755756106155, 10077.19382449417, 10122.190224782147, 10134.391936483835, 10224.130948869131, 7440.888386674096, 7441.144268409203, 7518.210587888193, 7539.170691224071, 7593.658285377061, 7801.2198780121535, 7805.297523222937, 7858.489198202098, 7876.133625301327, 7889.368850491832, 7256.564606123649, 7424.814379640552, 7495.850082998419, 7499.400047996038, 7569.470137090571, 7173.0, 7255.347018768336, 7286.708531658886, 7359.558426494427, 7362.484626076123, 6863.691133898887, 6915.0, 6943.9652801735665, 6948.756793512405, 6950.65246737651, 4727.101850648366, 4843.636727245541, 4949.777260023236, 4956.504349565014, 4964.106460837084, 4170.22850772608, 4878.170710979071, 4913.533214344631, 4926.753662316798, 4930.285108659296], "value": 4454.628881465053, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.0477374787553, 299.54917848637797, 312.0995926751322, 320.8347700934018, 322.10294330290117, 4984.859538490755, 5148.871278218046, 5285.603579731521, 5538.363088244853, 5577.302837145352, 5271.446498117691, 5434.864128396792, 5464.808731694391, 5521.3926468088575, 5546.86132846679, 3137.403893260285, 3493.9301213975705, 4021.5375231848275, 4098.877033688979, 4146.771927543984, 3229.9677003229904, 3962.9009274768073, 4084.326086195774, 4153.107081977374, 4161.500619925613, 3751.212245428463, 4118.608732170454, 4127.525334586516, 4131.6281534661875, 4138.317177665682, 3317.303366293081, 3381.9830900845336, 3533.187366905613, 4066.064805094845, 4088.632023117904, 2891.2049186473887, 3101.984490077535, 3609.4224924012296, 3615.927681446383, 3624.151682641845, 4416.757078360721, 4886.51134886512, 5141.280220769096, 5158.561522270612, 5192.454792246797, 4662.7202367858035, 4752.405949256343, 4770.833020844254, 4771.928421073687, 4802.183628783116, 4733.360996265529, 5055.671381360236, 5539.418361072069, 5605.831825045255, 5712.0, 3462.3940810358085, 3764.96691786435, 3857.8010533995634, 3870.78710670913, 3994.6404823565726, 4918.8278410255825, 5091.745412729349, 5523.199136125239, 5737.062140684413, 6025.879482410371, 3377.915552111217, 3994.0, 4032.6169013944213, 4043.5147782265726, 4059.6346328830546, 4087.305158123126, 4122.6083522065355, 4127.0, 4127.525334586501, 4135.110951145458, 3759.736818422739, 4500.932486012713, 4741.454732705714, 4742.644301677388, 4770.403699537558, 5480.287562616834, 5709.714514274353, 5735.541156707537, 5801.825945221565, 5877.5591830612875, 1197.6886009637558, 1202.20053664314, 1260.5147018397984, 1286.7812471879804, 1426.6233085072954, 832.3133414932583, 854.5171977832505, 873.733511279066, 878.9648414063494, 880.7533890510667, 5205.022091605166, 5331.86670333237, 5355.491228333302, 5401.513863752281, 5409.594280428983, 5576.7211639417865, 5684.261046063985, 5914.427224157505, 5998.820035398945, 6020.548458865603, 4564.794584243717, 4790.760461976887, 4913.729744864067, 5023.764424830988, 5540.418256083134, 2888.321244507539, 3141.607299087614, 3174.587308647676, 3179.5374127900964, 3185.665505121975, 4998.800047998112, 6248.656323902138, 6256.249250090018, 6343.492520598341, 6425.626546063354, 5009.474005229471, 5371.75827087782, 5651.491365777099, 6241.375862413723, 6263.467605253513, 4867.805287788448, 5285.947140528546, 6169.475594574512, 6269.655168965751, 6343.841403964845, 5720.570957178312, 5996.400359963969, 6003.369646187088, 6063.181470501602, 6066.4540191382985, 4766.880827979328, 4950.381202349707, 5745.9425405745405, 5931.258592675916, 5969.373215812451, 4860.198067318995, 5503.862403439786, 5796.174205125372, 6154.661493617894, 6314.62112273265, 3769.4716397796055, 3963.781991990468, 3969.5633480316565, 3979.6020397959974, 3983.2830090583975, 5730.885382292247, 5978.701064946648, 5992.610480318765, 6051.243594550681, 6061.181740464981, 5114.874727559931, 5522.86192845166, 5557.166135175302, 5912.167228159113, 5943.227380440515, 5588.608797384307, 5768.221290125779, 5827.621204621686, 5839.357670656309, 5855.882882342415, 5384.380796208487, 5709.714514274353, 5726.112452569859, 5727.427257274239, 5731.790197489419, 4984.984084628893, 5381.165919282517, 5671.234383358194, 5813.476787089182, 5853.619514731528, 5744.597878148489, 5745.109508026263, 5748.223989761329, 5778.219940308173, 5840.8247752567495], "value": 4161.853645321184, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.7482445136365, 646.2729429391934, 712.6187489693016, 716.1370548489072, 717.4762423430899, 12122.0, 15080.698386032273, 15164.77252841207, 15165.848341516608, 15169.165695886735, 14165.78751318731, 14925.92537037316, 15464.84535154651, 15493.288674481948, 15856.84143158568, 920.3373571028882, 923.7182659288915, 931.5575101826646, 931.5621657820814, 933.733885842535, 733.6478490324647, 922.1562270522497, 956.6795123633591, 959.2757468111566, 959.4003747657714, 986.9703908882746, 990.5592011554861, 990.6730778842965, 992.5731935267831, 995.8854731705837, 949.55370975641, 954.1222075690381, 979.2459805949414, 981.4111533080167, 986.5856340337076, 691.7371398868448, 731.1445608637906, 738.479372042714, 738.8152961759561, 739.5156172706886, 14742.631434214123, 14837.851621483755, 15083.321250543751, 15170.544883653509, 15175.848241517553, 12477.75044499105, 12562.497500099986, 12620.936895315557, 12810.10329276951, 12931.48274069036, 9921.355111917703, 10015.198784097327, 10027.59889604415, 10257.281990260684, 10321.58713651453, 3526.3828829954655, 3551.5027896094566, 3562.928741425157, 3641.799701016443, 3645.908852278688, 69331.0, 77745.22254777436, 78409.60795196044, 79468.60265698693, 79780.0, 9681.854772178423, 9682.75793105178, 9686.176674982706, 9737.0, 9750.122488976027, 22273.774942378324, 22290.99690513931, 22324.995375208153, 22413.66379504309, 22637.773622263896, 7269.491135620561, 7537.2462753724185, 7743.264389882952, 7847.529148251123, 7988.600569971478, 4032.1330913853662, 4059.939100913489, 4077.4087757275624, 4079.469668943078, 4081.428599996003, 3455.1189446691437, 3457.792532448061, 3490.2495963367996, 3525.153963048901, 3554.182538016271, 626.7211091064432, 634.6826586706646, 638.7093872288098, 647.4270270810367, 648.5103746671233, 535.2132365422822, 561.668615516847, 587.6953163975967, 588.9941100589025, 594.625386006813, 8095.0, 8149.633266503021, 8151.1441298662785, 8242.258196762321, 8322.916770832335, 582.5718097198555, 585.7949717598806, 605.7365046204915, 611.461913516103, 619.6220305613572, 3624.5650521937537, 3762.529683789526, 3853.845846166122, 3917.177392747498, 3936.252112098692, 83288.58355708304, 86183.0, 86767.56616217004, 88318.0, 88320.0, 73915.0, 74680.62659686667, 75360.62319688476, 75654.24345756472, 75905.0, 25705.871470642527, 25817.483650327264, 41945.95135121646, 63530.68234658797, 64249.35750642527, 7634.847303053797, 7664.770056898302, 7687.884681729666, 7710.575918324546, 7723.918651388698, 40746.1850762989, 41780.791096044326, 43104.56895431005, 43222.351664724425, 43528.0, 54680.72659636677, 54839.45160548343, 56468.717656411456, 58965.410345895994, 59498.0, 10434.37393756377, 10444.895551044696, 10499.0, 10637.521311541153, 11032.613858514913, 9885.85171222418, 10995.120390368911, 11076.092747215374, 11215.485909402127, 11234.213605047567, 9737.269704772028, 10231.641892533726, 10464.058234758908, 10475.895241047798, 10736.087432568007, 10253.282270241012, 10268.281220314806, 10361.274710770174, 10522.473876306001, 10803.675889723321, 10023.248256380946, 10070.798584028425, 10244.692659220234, 10252.487375631039, 10297.588096476356, 8776.078511756174, 8811.030786613595, 8840.381173317806, 8854.822903542023, 8963.417377870417, 8636.093210212837, 8873.866891996497, 8996.730098097067, 9019.233365164035, 9040.003182749644], "value": 6646.272412624068, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1694.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 154.0, 161.0, 116.0, 116.0, 116.0, 116.0, 116.0, 115.0, 114.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 124.0, 142.0, 199.0, 122.0, 145.0, 141.0, 123.0, 205.0, 148.0], "value": 150.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 208.0, 236.0, 276.0, 241.0, 241.0, 272.0, 220.0, 199.0, 197.0], "value": 228.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 75.0, 174.0, 58.0, 60.0, 58.0, 65.0, 136.0, 59.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 114.0, 203.0, 112.0, 114.0, 85.0, 122.0, 81.0, 81.0, 148.0], "value": 114.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 127.0, 132.0, 86.0, 87.0, 86.0, 88.0, 85.0, 91.0, 85.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 148.0, 107.0, 94.0, 94.0, 148.0, 132.0, 95.0, 95.0, 105.0], "value": 112.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 143.0, 99.0, 92.0, 107.0, 111.0, 137.0, 101.0, 139.0, 153.0], "value": 118.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 60.0, 82.0, 84.0, 89.0, 88.0, 131.0, 60.0, 59.0], "value": 77.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 70.0, 74.0, 79.0, 79.0, 68.0, 96.0, 64.0, 92.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 76.0, 88.0, 99.0, 113.0, 106.0, 80.0, 110.0, 110.0, 108.0], "value": 99.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 139.0, 136.0, 132.0, 140.0, 147.0, 139.0, 206.0, 153.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 206.0, 166.0, 183.0, 223.0, 220.0, 149.0, 220.0, 226.0, 167.0], "value": 198.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 66.0, 66.0, 65.0, 67.0, 69.0, 65.0, 68.0, 63.0], "value": 67.1, "unit": "score"}]}]} 07:35:30 INFO - Return code: 0 07:35:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:35:30 ERROR - # TBPL SUCCESS # 07:35:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:35:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:35:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:35:30 INFO - 2016-05-24 07:35:30.364 screenresolution[2214:22211] starting screenresolution argv=screenresolution get 07:35:30 INFO - 2016-05-24 07:35:30.366 screenresolution[2214:22211] Display 0: 1600x1200x32@60 07:35:30 INFO - 2016-05-24 07:35:30.372 screenresolution[2215:22214] starting screenresolution argv=screenresolution list 07:35:30 INFO - Available Modes on Display 0 07:35:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:35:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:35:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:35:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:35:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:35:30 INFO - Intel Iris: 07:35:30 INFO - Chipset Model: Intel Iris 07:35:30 INFO - Type: GPU 07:35:30 INFO - Bus: Built-In 07:35:30 INFO - VRAM (Dynamic, Max): 1536 MB 07:35:30 INFO - Vendor: Intel (0x8086) 07:35:30 INFO - Device ID: 0x0a2e 07:35:30 INFO - Revision ID: 0x0009 07:35:30 INFO - Displays: 07:35:30 INFO - Display: 07:35:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:35:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:35:30 INFO - Display Serial Number: 1600x1200 60 07:35:30 INFO - Main Display: Yes 07:35:30 INFO - Mirror: Off 07:35:30 INFO - Online: Yes 07:35:30 INFO - Rotation: Supported 07:35:30 INFO - Return code: 0 07:35:30 INFO - Running post-action listener: _resource_record_post_action 07:35:30 INFO - ##### 07:35:30 INFO - ##### Finished run-tests step (success) 07:35:30 INFO - ##### 07:35:30 INFO - Running post-run listener: _resource_record_post_run 07:35:30 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 978944; Write bytes: 698889728; Read time: 11; Write time: 2082 07:35:30 INFO - TinderboxPrint: CPU usage
54.1% 07:35:30 INFO - TinderboxPrint: I/O read bytes / time
978,944 / 11 07:35:30 INFO - TinderboxPrint: I/O write bytes / time
698,889,728 / 2,082 07:35:30 INFO - TinderboxPrint: CPU idle
2,223.7 (45.9%) 07:35:30 INFO - TinderboxPrint: CPU system
974.2 (20.1%) 07:35:30 INFO - TinderboxPrint: CPU user
1,647.8 (34.0%) 07:35:30 INFO - TinderboxPrint: Swap in / out
396,800,000 / 0 07:35:30 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 396984832; Write bytes: 398779904; Read time: 29950; Write time: 738 07:35:30 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 978944; Write bytes: 299261952; Read time: 11; Write time: 1342 07:35:31 INFO - Running post-run listener: _upload_blobber_files 07:35:31 INFO - Blob upload gear active. 07:35:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:35:31 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 07:35:31 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'] 07:35:31 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 07:35:31 INFO - (blobuploader) - INFO - Open directory for files ... 07:35:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:35:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:35:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:35:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:35:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:35:32 INFO - (blobuploader) - INFO - Done attempting. 07:35:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:35:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:35:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:35:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:35:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:35:33 INFO - (blobuploader) - INFO - Done attempting. 07:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:35:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:35:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:35:35 INFO - (blobuploader) - INFO - Done attempting. 07:35:35 INFO - (blobuploader) - INFO - Iteration through files over. 07:35:35 INFO - Return code: 0 07:35:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:35:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:35:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c06264b73e6fb0492abd7999cf662ddf16fc4910d5e6b56e10c0214e13a497f74b9e7db305d9b657cf6230068f8a7e91ac1bda8c5a8067f71e1acf116a663583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0677b9666b461fa12af427c9d66b26f6b08f4811d780f7e937e5e532fe383d30388b5e2792f787596ccd991e5c78222c07a393189b4caa8caf28f63202f80c2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66a8d792da0d8482a0a382b1a89ac3ad7f4f51ab4008b4ca9f988a114aa9ca8dc9fa691e5ecba0395f8f9e2f06fa6e9ffc820b50ede9c8d72a062485dd1e81bd"} 07:35:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:35:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:35:35 INFO - Contents: 07:35:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c06264b73e6fb0492abd7999cf662ddf16fc4910d5e6b56e10c0214e13a497f74b9e7db305d9b657cf6230068f8a7e91ac1bda8c5a8067f71e1acf116a663583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0677b9666b461fa12af427c9d66b26f6b08f4811d780f7e937e5e532fe383d30388b5e2792f787596ccd991e5c78222c07a393189b4caa8caf28f63202f80c2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66a8d792da0d8482a0a382b1a89ac3ad7f4f51ab4008b4ca9f988a114aa9ca8dc9fa691e5ecba0395f8f9e2f06fa6e9ffc820b50ede9c8d72a062485dd1e81bd"} 07:35:35 INFO - Running post-run listener: copy_logs_to_upload_dir 07:35:35 INFO - Copying logs to upload dir... 07:35:35 INFO - mkdir: /builds/slave/test/build/upload/logs 07:35:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.652839 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-24 07:35:35.864650) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 07:35:35.868331) ========= 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.8qid9oRzpB/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.cqweLfF1om/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c06264b73e6fb0492abd7999cf662ddf16fc4910d5e6b56e10c0214e13a497f74b9e7db305d9b657cf6230068f8a7e91ac1bda8c5a8067f71e1acf116a663583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0677b9666b461fa12af427c9d66b26f6b08f4811d780f7e937e5e532fe383d30388b5e2792f787596ccd991e5c78222c07a393189b4caa8caf28f63202f80c2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66a8d792da0d8482a0a382b1a89ac3ad7f4f51ab4008b4ca9f988a114aa9ca8dc9fa691e5ecba0395f8f9e2f06fa6e9ffc820b50ede9c8d72a062485dd1e81bd"} build_url:https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011160 build_url: 'https://queue.taskcluster.net/v1/task/Crl-wAZwS22E5YoyjIAQAA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c06264b73e6fb0492abd7999cf662ddf16fc4910d5e6b56e10c0214e13a497f74b9e7db305d9b657cf6230068f8a7e91ac1bda8c5a8067f71e1acf116a663583", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0677b9666b461fa12af427c9d66b26f6b08f4811d780f7e937e5e532fe383d30388b5e2792f787596ccd991e5c78222c07a393189b4caa8caf28f63202f80c2b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/66a8d792da0d8482a0a382b1a89ac3ad7f4f51ab4008b4ca9f988a114aa9ca8dc9fa691e5ecba0395f8f9e2f06fa6e9ffc820b50ede9c8d72a062485dd1e81bd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 07:35:35.899562) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:35:35.900415) ========= 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.8qid9oRzpB/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.cqweLfF1om/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005187 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 07:35:35.953241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 07:35:35.953952) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 07:35:35.954418) ========= ========= Total master_lag: 0.17 =========