builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0191 starttime: 1464255695.02 results: success (0) buildid: 20160526012938 builduid: 3c7e2e01a36d4d12938f41112d3d3058 revision: ebf96465a8de20345f7b8d176d827ec538ecbd09 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.016607) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.017116) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.017443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.040288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.040715) ========= 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.XDT9HbACkf/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.YOWyi4dRZU/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-26 02:41:35-- 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% 203M=0s 2016-05-26 02:41:35 (203 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075063 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.139065) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.139426) ========= 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.XDT9HbACkf/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.YOWyi4dRZU/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.028249 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:35.188053) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 02:41:35.188579) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ebf96465a8de20345f7b8d176d827ec538ecbd09 --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 ebf96465a8de20345f7b8d176d827ec538ecbd09 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XDT9HbACkf/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.YOWyi4dRZU/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-26 02:41:35,266 truncating revision to first 12 chars 2016-05-26 02:41:35,266 Setting DEBUG logging. 2016-05-26 02:41:35,266 attempt 1/10 2016-05-26 02:41:35,266 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ebf96465a8de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 02:41:36,295 unpacking tar archive at: fx-team-ebf96465a8de/testing/mozharness/ program finished with exit code 0 elapsedTime=1.294205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-26 02:41:36.503805) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:36.504165) ========= script_repo_revision: ebf96465a8de20345f7b8d176d827ec538ecbd09 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:36.504625) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:36.504926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 02:41:36.521786) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 02:41:36.522131) ========= /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.XDT9HbACkf/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.YOWyi4dRZU/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:41:36 INFO - MultiFileLogger online at 20160526 02:41:36 in /builds/slave/test 02:41:36 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 02:41:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:41:36 INFO - {'append_to_log': False, 02:41:36 INFO - 'base_work_dir': '/builds/slave/test', 02:41:36 INFO - 'blob_upload_branch': 'Fx-Team', 02:41:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:41:36 INFO - 'branch': 'Fx-Team', 02:41:36 INFO - 'buildbot_json_path': 'buildprops.json', 02:41:36 INFO - 'config_files': ('talos/mac_config.py',), 02:41:36 INFO - 'default_actions': ('clobber', 02:41:36 INFO - 'read-buildbot-config', 02:41:36 INFO - 'download-and-extract', 02:41:36 INFO - 'populate-webroot', 02:41:36 INFO - 'create-virtualenv', 02:41:36 INFO - 'install', 02:41:36 INFO - 'run-tests'), 02:41:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:41:36 INFO - 'download_minidump_stackwalk': True, 02:41:36 INFO - 'download_symbols': 'ondemand', 02:41:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:41:36 INFO - 'tooltool.py': '/tools/tooltool.py', 02:41:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:41:36 INFO - '/tools/misc-python/virtualenv.py')}, 02:41:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:41:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:41:36 INFO - 'installer_path': 'installer.exe', 02:41:36 INFO - 'log_level': 'info', 02:41:36 INFO - 'log_name': 'talos', 02:41:36 INFO - 'log_to_console': True, 02:41:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:41:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:41:36 INFO - 'opt_config_files': (), 02:41:36 INFO - 'pip_index': False, 02:41:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:36 INFO - 'cmd': ('bash', 02:41:36 INFO - '-c', 02:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:36 INFO - 'enabled': True, 02:41:36 INFO - 'halt_on_failure': False, 02:41:36 INFO - 'name': 'check_screen_resolution'},), 02:41:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:41:36 INFO - 'cmd': ('bash', 02:41:36 INFO - '-c', 02:41:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:41:36 INFO - 'enabled': True, 02:41:36 INFO - 'halt_on_failure': False, 02:41:36 INFO - 'name': 'check_screen_resolution'},), 02:41:36 INFO - 'run_cmd_checks_enabled': True, 02:41:36 INFO - 'sps_profile': False, 02:41:36 INFO - 'sps_profile_interval': 0, 02:41:36 INFO - 'suite': 'dromaeojs-e10s', 02:41:36 INFO - 'system_bits': '32', 02:41:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:41:36 INFO - 'title': 't-yosemite-r7-0191', 02:41:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:41:36 INFO - 'use_talos_json': True, 02:41:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:41:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:41:36 INFO - 'work_dir': 'build'} 02:41:36 INFO - ##### 02:41:36 INFO - ##### Running clobber step. 02:41:36 INFO - ##### 02:41:36 INFO - Running pre-action listener: _resource_record_pre_action 02:41:36 INFO - Running main action method: clobber 02:41:36 INFO - rmtree: /builds/slave/test/build 02:41:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:41:38 INFO - Running post-action listener: _resource_record_post_action 02:41:38 INFO - ##### 02:41:38 INFO - ##### Finished clobber step (success) 02:41:38 INFO - ##### 02:41:38 INFO - ##### 02:41:38 INFO - ##### Running read-buildbot-config step. 02:41:38 INFO - ##### 02:41:38 INFO - Running pre-action listener: _resource_record_pre_action 02:41:38 INFO - Running main action method: read_buildbot_config 02:41:38 INFO - Using buildbot properties: 02:41:38 INFO - { 02:41:38 INFO - "project": "", 02:41:38 INFO - "product": "firefox", 02:41:38 INFO - "script_repo_revision": "production", 02:41:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 02:41:38 INFO - "repository": "", 02:41:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 02:41:38 INFO - "buildid": "20160526012938", 02:41:38 INFO - "pgo_build": "False", 02:41:38 INFO - "basedir": "/builds/slave/test", 02:41:38 INFO - "buildnumber": 21, 02:41:38 INFO - "slavename": "t-yosemite-r7-0191", 02:41:38 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:41:38 INFO - "platform": "yosemite_r7", 02:41:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:41:38 INFO - "branch": "fx-team", 02:41:38 INFO - "repo_path": "integration/fx-team", 02:41:38 INFO - "revision": "ebf96465a8de20345f7b8d176d827ec538ecbd09", 02:41:38 INFO - "stage_platform": "macosx64", 02:41:38 INFO - "builduid": "3c7e2e01a36d4d12938f41112d3d3058", 02:41:38 INFO - "slavebuilddir": "test" 02:41:38 INFO - } 02:41:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 02:41:38 INFO - Running post-action listener: _resource_record_post_action 02:41:38 INFO - ##### 02:41:38 INFO - ##### Finished read-buildbot-config step (success) 02:41:38 INFO - ##### 02:41:38 INFO - ##### 02:41:38 INFO - ##### Running download-and-extract step. 02:41:38 INFO - ##### 02:41:38 INFO - Running pre-action listener: _resource_record_pre_action 02:41:38 INFO - Running main action method: download_and_extract 02:41:38 INFO - mkdir: /builds/slave/test/build/tests 02:41: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')]} 02:41:38 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:41:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:41:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:41:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:40 INFO - Downloaded 1199 bytes. 02:41:40 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 02:41:40 INFO - Using the following test package requirements: 02:41:40 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:41:40 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 02:41:40 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 02:41:40 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'jsshell-mac.zip'], 02:41:40 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 02:41:40 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:41:40 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 02:41:40 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 02:41:40 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 02:41:40 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:41:40 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 02:41:40 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:41:40 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')]} 02:41:40 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:41:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:41:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:41:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:41 INFO - Downloaded 18622392 bytes. 02:41:41 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'] 02:41:41 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 02:41:44 INFO - Return code: 0 02:41:44 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 02:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:44 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:41:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:41:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:41:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:45 INFO - Downloaded 18622392 bytes. 02:41:45 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'] 02:41:45 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 02:41:49 INFO - Return code: 0 02:41:49 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')]} 02:41:49 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:41:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:41:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:41:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:51 INFO - Downloaded 10834034 bytes. 02:41:51 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'] 02:41:51 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 02:41:52 INFO - Return code: 0 02:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:52 INFO - https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:41:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:41:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:41:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 02:41:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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 02:41:55 INFO - Downloaded 110500006 bytes. 02:41:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:41:55 INFO - mkdir: /builds/slave/test/properties 02:41:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:41:55 INFO - Writing to file /builds/slave/test/properties/build_url 02:41:55 INFO - Contents: 02:41:55 INFO - build_url:https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:41:56 INFO - Running post-action listener: _resource_record_post_action 02:41:56 INFO - Running post-action listener: set_extra_try_arguments 02:41:56 INFO - ##### 02:41:56 INFO - ##### Finished download-and-extract step (success) 02:41:56 INFO - ##### 02:41:56 INFO - ##### 02:41:56 INFO - ##### Running populate-webroot step. 02:41:56 INFO - ##### 02:41:56 INFO - Running pre-action listener: _resource_record_pre_action 02:41:56 INFO - Running main action method: populate_webroot 02:41:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:41:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:41:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:41:56 INFO - '--tppagecycles', 02:41:56 INFO - '7'], 02:41:56 INFO - 'tests': ['tsvgm']}}, 02:41:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:41:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:41:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:41:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:41:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:41:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['damp', 'tps']}, 02:41:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'tests': ['damp', 'tps']}, 02:41:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['dromaeo_dom']}, 02:41:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:41:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['a11yr', 02:41:56 INFO - 'ts_paint', 02:41:56 INFO - 'tpaint', 02:41:56 INFO - 'sessionrestore', 02:41:56 INFO - 'sessionrestore_no_auto_restore', 02:41:56 INFO - 'tabpaint']}, 02:41:56 INFO - 'other-e10s': {'tests': ['a11yr', 02:41:56 INFO - 'ts_paint', 02:41:56 INFO - 'tpaint', 02:41:56 INFO - 'sessionrestore', 02:41:56 INFO - 'sessionrestore_no_auto_restore', 02:41:56 INFO - 'tabpaint']}, 02:41:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:41:56 INFO - 'ts_paint', 02:41:56 INFO - 'tpaint', 02:41:56 INFO - 'sessionrestore', 02:41:56 INFO - 'sessionrestore_no_auto_restore', 02:41:56 INFO - 'tabpaint']}, 02:41:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:41:56 INFO - 'ts_paint', 02:41:56 INFO - 'tpaint', 02:41:56 INFO - 'sessionrestore', 02:41:56 INFO - 'sessionrestore_no_auto_restore', 02:41:56 INFO - 'tabpaint']}, 02:41:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['a11yr', 02:41:56 INFO - 'ts_paint', 02:41:56 INFO - 'tpaint', 02:41:56 INFO - 'sessionrestore', 02:41:56 INFO - 'sessionrestore_no_auto_restore', 02:41:56 INFO - 'tabpaint']}, 02:41:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['a11yr', 02:41:56 INFO - 'ts_paint', 02:41:56 INFO - 'tpaint', 02:41:56 INFO - 'sessionrestore', 02:41:56 INFO - 'sessionrestore_no_auto_restore', 02:41:56 INFO - 'tabpaint']}, 02:41:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['tsvgx', 02:41:56 INFO - 'tsvgr_opacity', 02:41:56 INFO - 'tart', 02:41:56 INFO - 'tscrollx', 02:41:56 INFO - 'cart']}, 02:41:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:41:56 INFO - 'tsvgr_opacity', 02:41:56 INFO - 'tart', 02:41:56 INFO - 'tscrollx', 02:41:56 INFO - 'cart']}, 02:41:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'talos_options': ['--disable-e10s'], 02:41:56 INFO - 'tests': ['tp5o']}, 02:41:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'tests': ['tp5o']}, 02:41:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'talos_options': ['--disable-e10s', 02:41:56 INFO - '--xperf_path', 02:41:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:56 INFO - 'tests': ['tp5n']}, 02:41:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:41:56 INFO - 'talos_options': ['--xperf_path', 02:41:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:41:56 INFO - 'tests': ['tp5n']}}, 02:41:56 INFO - 'talos.zip': {'path': '', 02:41:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:41:56 INFO - Running post-action listener: _resource_record_post_action 02:41:56 INFO - ##### 02:41:56 INFO - ##### Finished populate-webroot step (success) 02:41:56 INFO - ##### 02:41:56 INFO - ##### 02:41:56 INFO - ##### Running create-virtualenv step. 02:41:56 INFO - ##### 02:41:56 INFO - Running pre-action listener: _resource_record_pre_action 02:41:56 INFO - Running main action method: create_virtualenv 02:41:56 INFO - Creating virtualenv /builds/slave/test/build/venv 02:41:56 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 02:41:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:41:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:41:56 INFO - Using real prefix '/tools/python27' 02:41:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:41:57 INFO - Installing distribute.............................................................................................................................................................................................done. 02:41:59 INFO - Installing pip.................done. 02:41:59 INFO - Return code: 0 02:41:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:41:59 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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:41:59 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 02:41:59 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 02:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:41:59 INFO - 'HOME': '/Users/cltbld', 02:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:41:59 INFO - 'LOGNAME': 'cltbld', 02:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:41:59 INFO - 'MOZ_NO_REMOTE': '1', 02:41:59 INFO - 'NO_EM_RESTART': '1', 02:41:59 INFO - 'PAGER': '/bin/cat', 02:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:41:59 INFO - 'PWD': '/builds/slave/test', 02:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:41:59 INFO - 'SHELL': '/bin/bash', 02:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:41:59 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:41:59 INFO - 'USER': 'cltbld', 02:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:41:59 INFO - 'XPC_FLAGS': '0x0', 02:41:59 INFO - 'XPC_SERVICE_NAME': '0', 02:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:00 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 02:42:00 INFO - Cleaning up... 02:42:00 INFO - Return code: 0 02:42:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:42:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:00 INFO - 'HOME': '/Users/cltbld', 02:42:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:00 INFO - 'LOGNAME': 'cltbld', 02:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:00 INFO - 'MOZ_NO_REMOTE': '1', 02:42:00 INFO - 'NO_EM_RESTART': '1', 02:42:00 INFO - 'PAGER': '/bin/cat', 02:42:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:00 INFO - 'PWD': '/builds/slave/test', 02:42:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:00 INFO - 'SHELL': '/bin/bash', 02:42:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:00 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:00 INFO - 'USER': 'cltbld', 02:42:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:00 INFO - 'XPC_FLAGS': '0x0', 02:42:00 INFO - 'XPC_SERVICE_NAME': '0', 02:42:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:00 INFO - Downloading/unpacking psutil>=3.1.1 02:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:42:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:03 INFO - Installing collected packages: psutil 02:42:03 INFO - Running setup.py install for psutil 02:42:03 INFO - building 'psutil._psutil_osx' extension 02:42:03 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 02:42:04 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 02:42:04 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 02:42:04 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 02:42:04 INFO - building 'psutil._psutil_posix' extension 02:42:04 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 02:42:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:42:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:42:04 INFO - ^ 02:42:04 INFO - 1 warning generated. 02:42:04 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 02:42:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:42:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:42:04 INFO - Successfully installed psutil 02:42:04 INFO - Cleaning up... 02:42:04 INFO - Return code: 0 02:42:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:42:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:04 INFO - 'HOME': '/Users/cltbld', 02:42:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:04 INFO - 'LOGNAME': 'cltbld', 02:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:04 INFO - 'MOZ_NO_REMOTE': '1', 02:42:04 INFO - 'NO_EM_RESTART': '1', 02:42:04 INFO - 'PAGER': '/bin/cat', 02:42:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:04 INFO - 'PWD': '/builds/slave/test', 02:42:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:04 INFO - 'SHELL': '/bin/bash', 02:42:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:04 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:04 INFO - 'USER': 'cltbld', 02:42:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:04 INFO - 'XPC_FLAGS': '0x0', 02:42:04 INFO - 'XPC_SERVICE_NAME': '0', 02:42:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:42:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:42:07 INFO - Installing collected packages: mozsystemmonitor 02:42:07 INFO - Running setup.py install for mozsystemmonitor 02:42:07 INFO - Successfully installed mozsystemmonitor 02:42:07 INFO - Cleaning up... 02:42:07 INFO - Return code: 0 02:42:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:42:07 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')]} 02:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:07 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')]} 02:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:07 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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:07 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 02:42:07 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 02:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:07 INFO - 'HOME': '/Users/cltbld', 02:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:07 INFO - 'LOGNAME': 'cltbld', 02:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:07 INFO - 'MOZ_NO_REMOTE': '1', 02:42:07 INFO - 'NO_EM_RESTART': '1', 02:42:07 INFO - 'PAGER': '/bin/cat', 02:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:07 INFO - 'PWD': '/builds/slave/test', 02:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:07 INFO - 'SHELL': '/bin/bash', 02:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:07 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:07 INFO - 'USER': 'cltbld', 02:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:07 INFO - 'XPC_FLAGS': '0x0', 02:42:07 INFO - 'XPC_SERVICE_NAME': '0', 02:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:07 INFO - Downloading/unpacking blobuploader==1.2.4 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:10 INFO - Downloading blobuploader-1.2.4.tar.gz 02:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:42:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:42:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42: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 02:42:11 INFO - Downloading docopt-0.6.1.tar.gz 02:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:42:11 INFO - Installing collected packages: blobuploader, requests, docopt 02:42:11 INFO - Running setup.py install for blobuploader 02:42:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:42:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:42:11 INFO - Running setup.py install for requests 02:42:12 INFO - Running setup.py install for docopt 02:42:12 INFO - Successfully installed blobuploader requests docopt 02:42:12 INFO - Cleaning up... 02:42:12 INFO - Return code: 0 02:42:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:12 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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:12 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 02:42:12 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 02:42:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:12 INFO - 'HOME': '/Users/cltbld', 02:42:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:12 INFO - 'LOGNAME': 'cltbld', 02:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:12 INFO - 'MOZ_NO_REMOTE': '1', 02:42:12 INFO - 'NO_EM_RESTART': '1', 02:42:12 INFO - 'PAGER': '/bin/cat', 02:42:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:12 INFO - 'PWD': '/builds/slave/test', 02:42:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:12 INFO - 'SHELL': '/bin/bash', 02:42:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:12 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:12 INFO - 'USER': 'cltbld', 02:42:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:12 INFO - 'XPC_FLAGS': '0x0', 02:42:12 INFO - 'XPC_SERVICE_NAME': '0', 02:42:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:42:12 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-huECxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:42:12 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-DpIlH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:42:12 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-5hklRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Czo29T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-PxXOmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Pj1VcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-61dpqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-0z1pFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-xJto0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-npt84Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-uUcACh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:42:13 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ZekdOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-wSWN2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-m7Gnke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-u1qIwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-V9x4iJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:42:14 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-1r6NwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:42:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:42:14 INFO - Running setup.py install for manifestparser 02:42:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:42:14 INFO - Running setup.py install for mozcrash 02:42:14 INFO - Running setup.py install for mozdebug 02:42:14 INFO - Running setup.py install for mozdevice 02:42:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Running setup.py install for mozfile 02:42:15 INFO - Running setup.py install for mozhttpd 02:42:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Running setup.py install for mozinfo 02:42:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Running setup.py install for mozInstall 02:42:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:42:15 INFO - Running setup.py install for mozleak 02:42:15 INFO - Running setup.py install for mozlog 02:42:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:42:16 INFO - Running setup.py install for moznetwork 02:42:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:42:16 INFO - Running setup.py install for mozprocess 02:42:16 INFO - Running setup.py install for mozprofile 02:42:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:42:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:42:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:42:16 INFO - Running setup.py install for mozrunner 02:42:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:42:16 INFO - Running setup.py install for mozscreenshot 02:42:16 INFO - Running setup.py install for moztest 02:42:16 INFO - Running setup.py install for mozversion 02:42:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:42:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:42:17 INFO - Cleaning up... 02:42:17 INFO - Return code: 0 02:42:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:17 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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:17 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 02:42:17 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 02:42:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:17 INFO - 'HOME': '/Users/cltbld', 02:42:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:17 INFO - 'LOGNAME': 'cltbld', 02:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:17 INFO - 'MOZ_NO_REMOTE': '1', 02:42:17 INFO - 'NO_EM_RESTART': '1', 02:42:17 INFO - 'PAGER': '/bin/cat', 02:42:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:17 INFO - 'PWD': '/builds/slave/test', 02:42:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:17 INFO - 'SHELL': '/bin/bash', 02:42:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:17 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:17 INFO - 'USER': 'cltbld', 02:42:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:17 INFO - 'XPC_FLAGS': '0x0', 02:42:17 INFO - 'XPC_SERVICE_NAME': '0', 02:42:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:42:17 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-yFcewJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:42:17 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)) 02:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:42:17 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-M23zBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:42:17 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)) 02:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:42:17 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-vTSzup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:42:17 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)) 02:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:42:17 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-bAYQaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:42:17 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)) 02:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:42:17 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-muf5Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:42:17 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)) 02:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:42:17 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-zeRCde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-hU1hjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-NL0xDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-ikLF8h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-P256Wt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-HUUrj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-d4oolx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-uuAghk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-USUXGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:42:18 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)) 02:42:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:18 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-Kdp5d5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:42:19 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)) 02:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-nPervV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:42:19 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)) 02:42:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:42:19 INFO - Running setup.py (path:/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/pip-N1Qx1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:42:19 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)) 02:42:19 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)) 02:42:19 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)) 02:42:19 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)) 02:42:19 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)) 02:42:19 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)) 02:42:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:21 INFO - Downloading blessings-1.6.tar.gz 02:42:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:42:21 INFO - Installing collected packages: blessings 02:42:21 INFO - Running setup.py install for blessings 02:42:21 INFO - Successfully installed blessings 02:42:21 INFO - Cleaning up... 02:42:21 INFO - Return code: 0 02:42:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:42:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:22 INFO - Reading from file tmpfile_stdout 02:42:22 INFO - Current package versions: 02:42:22 INFO - blessings == 1.6 02:42:22 INFO - blobuploader == 1.2.4 02:42:22 INFO - docopt == 0.6.1 02:42:22 INFO - manifestparser == 1.1 02:42:22 INFO - mozInstall == 1.12 02:42:22 INFO - mozcrash == 0.17 02:42:22 INFO - mozdebug == 0.1 02:42:22 INFO - mozdevice == 0.48 02:42:22 INFO - mozfile == 1.2 02:42:22 INFO - mozhttpd == 0.7 02:42:22 INFO - mozinfo == 0.9 02:42:22 INFO - mozleak == 0.1 02:42:22 INFO - mozlog == 3.2 02:42:22 INFO - moznetwork == 0.27 02:42:22 INFO - mozprocess == 0.22 02:42:22 INFO - mozprofile == 0.28 02:42:22 INFO - mozrunner == 6.11 02:42:22 INFO - mozscreenshot == 0.1 02:42:22 INFO - mozsystemmonitor == 0.3 02:42:22 INFO - moztest == 0.7 02:42:22 INFO - mozversion == 1.4 02:42:22 INFO - psutil == 3.1.1 02:42:22 INFO - requests == 1.2.3 02:42:22 INFO - wsgiref == 0.1.2 02:42:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:22 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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:22 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 02:42:22 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 02:42:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:22 INFO - 'HOME': '/Users/cltbld', 02:42:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:22 INFO - 'LOGNAME': 'cltbld', 02:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:22 INFO - 'MOZ_NO_REMOTE': '1', 02:42:22 INFO - 'NO_EM_RESTART': '1', 02:42:22 INFO - 'PAGER': '/bin/cat', 02:42:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:22 INFO - 'PWD': '/builds/slave/test', 02:42:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:22 INFO - 'SHELL': '/bin/bash', 02:42:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:22 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:22 INFO - 'USER': 'cltbld', 02:42:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:22 INFO - 'XPC_FLAGS': '0x0', 02:42:22 INFO - 'XPC_SERVICE_NAME': '0', 02:42:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 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)) 02:42:22 INFO - Cleaning up... 02:42:22 INFO - Return code: 0 02:42:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:42:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:22 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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:22 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 02:42:22 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 02:42:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:22 INFO - 'HOME': '/Users/cltbld', 02:42:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:22 INFO - 'LOGNAME': 'cltbld', 02:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:22 INFO - 'MOZ_NO_REMOTE': '1', 02:42:22 INFO - 'NO_EM_RESTART': '1', 02:42:22 INFO - 'PAGER': '/bin/cat', 02:42:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:22 INFO - 'PWD': '/builds/slave/test', 02:42:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:22 INFO - 'SHELL': '/bin/bash', 02:42:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:22 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:22 INFO - 'USER': 'cltbld', 02:42:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:22 INFO - 'XPC_FLAGS': '0x0', 02:42:22 INFO - 'XPC_SERVICE_NAME': '0', 02:42:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:22 INFO - Downloading/unpacking jsonschema 02:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:42:25 INFO - Downloading/unpacking functools32 (from jsonschema) 02:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:42:25 INFO - Downloading functools32-3.2.3-2.tar.gz 02:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:42:25 INFO - warning: no files found matching '*.txt' 02:42:25 INFO - no previously-included directories found matching 'build' 02:42:25 INFO - no previously-included directories found matching 'dist' 02:42:25 INFO - no previously-included directories found matching '.git*' 02:42:25 INFO - Installing collected packages: jsonschema, functools32 02:42:25 INFO - Running setup.py install for functools32 02:42:25 INFO - warning: no files found matching '*.txt' 02:42:25 INFO - no previously-included directories found matching 'build' 02:42:25 INFO - no previously-included directories found matching 'dist' 02:42:25 INFO - no previously-included directories found matching '.git*' 02:42:26 INFO - Successfully installed jsonschema functools32 02:42:26 INFO - Cleaning up... 02:42:26 INFO - Return code: 0 02:42:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:42:26 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')]} 02:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:42:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:26 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')]} 02:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:42:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:42:26 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 0x10466ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104671570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fadf1d9c280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, '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.XDT9HbACkf/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.YOWyi4dRZU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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 02:42:26 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 02:42:26 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 02:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:42:26 INFO - 'HOME': '/Users/cltbld', 02:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:42:26 INFO - 'LOGNAME': 'cltbld', 02:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:42:26 INFO - 'MOZ_NO_REMOTE': '1', 02:42:26 INFO - 'NO_EM_RESTART': '1', 02:42:26 INFO - 'PAGER': '/bin/cat', 02:42:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:42:26 INFO - 'PWD': '/builds/slave/test', 02:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:42:26 INFO - 'SHELL': '/bin/bash', 02:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:42:26 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:42:26 INFO - 'USER': 'cltbld', 02:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:42:26 INFO - 'XPC_FLAGS': '0x0', 02:42:26 INFO - 'XPC_SERVICE_NAME': '0', 02:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:42:26 INFO - Downloading/unpacking flake8 02:42:26 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 02:42:26 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 02:42:26 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 02:42:26 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 02:42:26 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 02:42:26 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 02:42:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:42:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:42:28 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 02:42:28 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 02:42:28 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 02:42:28 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 02:42:28 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 02:42:28 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 02:42:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:42:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:42:29 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 02:42:29 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 02:42:29 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 02:42:29 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 02:42:29 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 02:42:29 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 02:42:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:42:29 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 02:42:29 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 02:42:29 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 02:42:29 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 02:42:29 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 02:42:29 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 02:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:42:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:42:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:42:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:42:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:42:30 INFO - no previously-included directories found matching 'docs/_build' 02:42:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:42:30 INFO - Running setup.py install for pep8 02:42:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:42:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:42:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:42:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:42:30 INFO - no previously-included directories found matching 'docs/_build' 02:42:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:42:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:42:30 INFO - Cleaning up... 02:42:30 INFO - Return code: 0 02:42:30 INFO - Running post-action listener: _resource_record_post_action 02:42:30 INFO - Running post-action listener: _start_resource_monitoring 02:42:30 INFO - Starting resource monitoring. 02:42:30 INFO - ##### 02:42:30 INFO - ##### Finished create-virtualenv step (success) 02:42:30 INFO - ##### 02:42:30 INFO - ##### 02:42:30 INFO - ##### Running install step. 02:42:30 INFO - ##### 02:42:30 INFO - Running pre-action listener: _resource_record_pre_action 02:42:30 INFO - Running main action method: install 02:42:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:42:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:42:30 INFO - Reading from file tmpfile_stdout 02:42:30 INFO - Detecting whether we're running mozinstall >=1.0... 02:42:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:42:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:42:30 INFO - Reading from file tmpfile_stdout 02:42:30 INFO - Output received: 02:42:30 INFO - Usage: mozinstall [options] installer 02:42:30 INFO - Options: 02:42:30 INFO - -h, --help show this help message and exit 02:42:30 INFO - -d DEST, --destination=DEST 02:42:30 INFO - Directory to install application into. [default: 02:42:30 INFO - "/builds/slave/test"] 02:42:30 INFO - --app=APP Application being installed. [default: firefox] 02:42:30 INFO - mkdir: /builds/slave/test/build/application 02:42:30 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'] 02:42:30 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 02:43:04 INFO - Reading from file tmpfile_stdout 02:43:04 INFO - Output received: 02:43:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:43:04 INFO - Running post-action listener: _resource_record_post_action 02:43:04 INFO - ##### 02:43:04 INFO - ##### Finished install step (success) 02:43:04 INFO - ##### 02:43:04 INFO - ##### 02:43:04 INFO - ##### Running run-tests step. 02:43:04 INFO - ##### 02:43:04 INFO - Running pre-action listener: _resource_record_pre_action 02:43:04 INFO - Running main action method: run_tests 02:43:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:43:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:43:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:43:04 INFO - 2016-05-26 02:43:04.513 screenresolution[1637:7492] starting screenresolution argv=screenresolution get 02:43:04 INFO - 2016-05-26 02:43:04.516 screenresolution[1637:7492] Display 0: 1600x1200x32@60 02:43:04 INFO - 2016-05-26 02:43:04.522 screenresolution[1638:7494] starting screenresolution argv=screenresolution list 02:43:04 INFO - Available Modes on Display 0 02:43:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:43:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:43:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:43:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:43:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:43:04 INFO - Intel Iris: 02:43:04 INFO - Chipset Model: Intel Iris 02:43:04 INFO - Type: GPU 02:43:04 INFO - Bus: Built-In 02:43:04 INFO - VRAM (Dynamic, Max): 1536 MB 02:43:04 INFO - Vendor: Intel (0x8086) 02:43:04 INFO - Device ID: 0x0a2e 02:43:04 INFO - Revision ID: 0x0009 02:43:04 INFO - Displays: 02:43:04 INFO - Display: 02:43:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:43:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:43:04 INFO - Display Serial Number: 1600x1200 60 02:43:04 INFO - Main Display: Yes 02:43:04 INFO - Mirror: Off 02:43:04 INFO - Online: Yes 02:43:04 INFO - Rotation: Supported 02:43:04 INFO - Return code: 0 02:43:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:43:04 INFO - Python 2.7.3 02:43:04 INFO - Return code: 0 02:43:04 INFO - grabbing minidump binary from tooltool 02:43:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:43:04 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 0x10450fd30>, '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 0x104333db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104506b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:43:04 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 02:43:04 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 02:43:04 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 02:43:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:43:04 INFO - Return code: 0 02:43:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:43:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:43:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:43:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:43:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:43:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:43:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:43:05 INFO - Return code: 0 02:43:05 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/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 02:43:05 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/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0191 --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 02:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XDT9HbACkf/Render', 02:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:43:05 INFO - 'HOME': '/Users/cltbld', 02:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:43:05 INFO - 'LOGNAME': 'cltbld', 02:43:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:43:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:43:05 INFO - 'MOZ_NO_REMOTE': '1', 02:43:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:43:05 INFO - 'NO_EM_RESTART': '1', 02:43:05 INFO - 'PAGER': '/bin/cat', 02:43:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:43:05 INFO - 'PWD': '/builds/slave/test', 02:43:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:43:05 INFO - 'SHELL': '/bin/bash', 02:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YOWyi4dRZU/Listeners', 02:43:05 INFO - 'TMPDIR': '/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/', 02:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:43:05 INFO - 'USER': 'cltbld', 02:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:43:05 INFO - 'XPC_FLAGS': '0x0', 02:43:05 INFO - 'XPC_SERVICE_NAME': '0', 02:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:43:05 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/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0191', '--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 02:43:06 INFO - mozversion application_buildid: 20160526012938 02:43:06 INFO - mozversion application_changeset: ebf96465a8de20345f7b8d176d827ec538ecbd09 02:43:06 INFO - mozversion application_display_name: Nightly 02:43:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:43:06 INFO - mozversion application_name: Firefox 02:43:06 INFO - mozversion application_remotingname: firefox 02:43:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:43:06 INFO - mozversion application_vendor: Mozilla 02:43:06 INFO - mozversion application_version: 49.0a1 02:43:06 INFO - mozversion platform_buildid: 20160526012938 02:43:06 INFO - mozversion platform_changeset: ebf96465a8de20345f7b8d176d827ec538ecbd09 02:43:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:43:06 INFO - mozversion platform_version: 49.0a1 02:43:06 INFO - using testdate: 1464255786 02:43:06 INFO - actual date: 1464255786 02:43:06 INFO - starting webserver on 'localhost:49278' 02:43:06 INFO - SUITE-START | Running 2 tests 02:43:06 INFO - TEST-START | dromaeo_css 02:43:06 INFO - operating with platform_type : mac_ 02:43:06 INFO - Initialising browser for dromaeo_css test... 02:43:06 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmp1Hi8Rb/profile http://localhost:49278/getInfo.html) 02:43:09 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 02:43:09 INFO - PROCESS | 1656 | colorDepth:24 02:43:09 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 02:43:09 INFO - PROCESS | 1656 | __metrics 02:43:09 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 02:43:09 INFO - TEST-INFO | 1656: exit 0 02:43:09 INFO - Browser initialized. 02:43:09 INFO - Running cycle 1/1 for dromaeo_css test... 02:43:09 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmp1Hi8Rb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:43:22 INFO - PROCESS | 1659 | RSS: Main: 252157952 02:43:22 INFO - PROCESS | 1659 | 02:44:11 INFO - PROCESS | 1659 | 1464255851763 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] 02:46:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 02:46:23 INFO - PROCESS | 1659 | RSS: Main: 264232960 02:46:23 INFO - PROCESS | 1659 | 02:49:26 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 02:49:26 INFO - PROCESS | 1659 | RSS: Main: 275886080 02:49:26 INFO - PROCESS | 1659 | 02:52:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 02:52:29 INFO - PROCESS | 1659 | RSS: Main: 277745664 02:52:29 INFO - PROCESS | 1659 | 02:55:30 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 02:55:31 INFO - PROCESS | 1659 | RSS: Main: 279515136 02:55:31 INFO - PROCESS | 1659 | 02:58:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 02:58:34 INFO - PROCESS | 1659 | RSS: Main: 282570752 02:58:34 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 03:01:37 INFO - PROCESS | 1659 | RSS: Main: 283328512 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | __start_tp_report 03:01:37 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 03:01:37 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 03:01:37 INFO - PROCESS | 1659 | |i|pagename|runs| 03:01:37 INFO - PROCESS | 1659 | |0;dojo.html;3501.457274122512;3509.684128428441;3533.4699795030747;3540.0795793093803;3545.92908141837;4987.301777751113;5743;5760.107183386572;5800.767969281234;5906;4585.312203169527;5775.682337471438;5786.450287222718;5821.708914554277;5851.941480585193;5157.76790044448;5211.817586384477;5284.2866213061325;5308.54877335425;5351.812686555972;91.9485327044213;5189.662671926331;5233.607479439038;5305.363356397238;5310.0441920454305;5250.737463126848;5252.921206181911;5279.60402969775;5281.181416880389;5372.328458942632;5293.735313234323;5318.494742999409;5327.813526526592;5366.195070739382;5381.13901775714;5149.2791009258735;5170.741462926839;5175.560077393426;5234.555062819665;5283.682979021271;5614.101743721026;5726;5731.140328950691;5768.97115514424;5794.65232086072;6209.285932117819;6231.345708700612;6244.718987645567;6261.906071408933;6265.968670156665;6151.907721384183;6187.597806142587;6205.844853878644;6227.034809604518;6318.336574659686;5972.372900845436;5974.820755377345;5979.671118088503;6001.849953751192;6043.969780151071;5293.12663410533;6254.437100660963;6430;6455.967720161463;6457.321981192001;6142.038385241494;6145.907811382833;6180;6231.937680623227;6246.250449945945;6258.530610204253;6281.623102613858;6316.557840951089;6324.747010119544;6352.460040896483;4843.297721830385;5541.778328866881;6240.500759939194;6249.875002499924;6267.9373206268265;6164.075388691643;6205.6586887720705;6210.7515699372425;6227.09707092469;6231.2522497300615;4943.456219815817;4945.109880221523;4971.975140124277;4973.328600638867;4982.975085124551;4396.648268138541;4423.071155057474;4437.33439984005;4495.280755079237;4502.57225563571;5780.624259423124;5845.561582881301;5930.080837470199;5931.288245410492;5949.881002379928;6293.527985401113;6300.684965751694;6343.904841427382;6348.936510634834;6352.587081839665;5819;5921.851953701192;5954.791582294599;5993.640381577119;6074.331823499411;5506.284183056177;5512.503874651301;5514.393416724157;5518.172274158828;5530.225768392428;5305.495977882095;6578.703958321887;6594;6596.80209593713;6611;6163.907541386972;6213.937860621336;6245.094461303176;6296.307406185224;6370.331115233019;6515.218173819172;6579.967100164468;6582.210134783857;6602.10871532337;6615.735370585123;6181.320054794059;6239.438450539473;6245.718942647501;6256.7810126645345;6277.2781130170615;6143.450468055707;6164.506839452923;6189.257289125334;6270.49836013127;6293.559450838397;6169.598976066542;6179.474744646755;6211.16149319836;6215.098810672517;6225.844353891189;4752.738599377068;6162.476189523941;6194.225721784777;6212.937870621417;6214.1921550199995;5907.852303692442;6007.519398448201;6054.727537260922;6083.6045657032155;6115.480184184395;6159.1069294951085;6191.117092866828;6215.906761398493;6224.937750622617;6240.438360547572;6101.542384321105;6142.32434432221;6152.200213972155;6156.938430615636;6162.537809664203 03:01:37 INFO - PROCESS | 1659 | |1;ext.html;489.72085911030706;512.9128048231801;530.7903378165624;533.6024661627089;573.9339975902773;41234.58765412349;43671.344929826;43689.78155109228;43764.124717505634;43852;44384.77807610957;44904;45324;45325;45554.772226138826;9486.762830929212;9610.807783844319;9629.27780416468;9664.985176556465;9681.70954871355;9654.179394751454;9741;9745.171660408874;9773.706788796311;9778.706638800848;9760.560774765117;9780.413175209475;9794.265430092735;9799.16707079899;9809.166220871235;9740.220782337432;9748.36635618686;9763.121319081245;9794.559244834;9810.754731131707;9524.619015239383;9618;9622.951885240529;9627.56675949584;9654.75863103421;41630.375544366856;41773.582264177276;42123.789381053204;42214.366784498256;42217;2739.2467071555284;3016.457037733207;3070.9846450767604;3278.164068162603;3291.226561757985;27445.90216391342;28532.857335713397;29564.113076607737;30302.393952121056;30341.634626441864;2664.61363102351;2682.7183145769804;2734.4667789781;2750.037486879596;2768.1280396675184;459595;463073;467058;468470;468780.99999999657;34922;35310.940671779885;35754.64245357513;35770.28459430797;36266.8186659065;36705.63294367075;37182.44226336607;37251;37256.254874902894;37321.813390933414;15193.392264309305;15334.386624535116;15340;15383.153926533923;15410;12529.812052819216;12805.871941280655;12835;12841.93579032099;12856.357182141042;7855.842883142305;7880.527168369801;7919.841603167904;8007.919920800718;8051;2793.25978615667;2804.2008027712004;3279.2785587170783;3308.9007329780147;3324.6176689680515;847.8007668197969;973.9464329461948;981.7054883534911;1039.4231201683137;1143.2911594811271;25462.49075018526;28262.43475130486;30377.848110759747;30392.392152156834;30675.38649227003;903.1781079218011;1050.695298363466;1072.9088027517591;1096.4572536594437;1142.874283828778;15583;15625.296861641253;15742.842571574367;15792.526224213292;15939.760903586455;645135;676592;710864;715477;722563;51762;52018.73990630022;65315.34684653188;65423.345766542676;65661.34338656552;1408.239550642662;1424.7150569885857;1429.2139323372103;1444.7471692453778;1450.5938337265582;29593.556096658136;29808.403831923675;30004.699953001065;30462.84768576143;30469.847650761603;56628.43371566397;56668.433315667964;56750.43249567616;56862.7156864213;56890.43109568852;518072;519452;519751;521410;521913;26627.866860665574;27837.304067398476;27918;28035.579466307612;28116;48190.75904620454;48576.514234858616;48683;49144.508554915425;50374.49625503698;39913.2017359657;40063.59936400599;40513.59486405098;40800.59199407968;41661;39071.023224419616;39266.036698165546;39860.80069599633;40434.39348409835;40539;38102;38873.222535548564;40354.19291614093;40425.595744042184;40489.79755101206;31499.52750708787;31761.682383175874;33642.3271534563;34583.96248112561;35262;24967.250982470556;25543.489130217662;25957.82388784934;26125.346866328495;26128.8693556531 03:01:37 INFO - PROCESS | 1659 | |2;jquery.html;333.2235890375425;341.84446077034954;379.41380567023964;385.7646835430387;400.6854619123988;19004.239830406783;20208.39374818754;20449.386518404433;20650.380488585328;21115.683264751042;20445.977701114924;20741.688874666856;20788.792112078874;20884;21249.36251912445;5632.239647647556;5789.218455508494;5991.281046274453;6022.126791615213;6032.457078862902;5852.67810270435;5924.111383292519;5990.790322338719;5996.520278377741;6004.549658775588;5566.888662226753;5898;5901.793437229698;5914.3494215636465;5929.970350148244;5651.604387692863;5708.914366284509;5898.705064746789;5903.055511118222;5904.498117660004;4273.50854651716;4676.3453116563705;4724.535394027828;4974.502549745032;5113.769880355393;24336.9048392821;24469.143579974614;24746.88639011249;24982;25031.873565689588;1917.6548221320156;2274.3397319956357;2303.67748515208;2364.9172278970327;2782.1514438096374;12517.499300027988;14327.78508322376;15015.924920375437;15038.398464061534;15293;1492.7611582146912;1513;1763.5855573940114;1784;1786.499340553941;109208;111246;111535;114556;114741;8919.464832110094;10407.739806504898;10585.100266477282;10959.178061645249;11095.5561777528;13258.535951241853;13290.601281961557;13424.93287533556;13438.260895650834;13533.729325413437;8263.297619702273;8388.412811103164;8426.115257897955;8450.87323690145;8731.781705457288;8337.416380853403;8576.528290943934;8719;8741;8872.423292485968;5703.344115426779;6580.177477815273;6690.565113267622;6698.22970358415;6765.1205343305055;1556.8598826105706;1573.2448424755953;1756.3589289909091;1880.868339216269;1881.89649569275;885.583775625455;906.4470672889532;920.5489310237936;938.9765255868658;1047.1831971062584;13674.794878076837;17251.05119218455;18012.819871801377;21439.392045596644;21539.67690484644;883.3992884838372;981.4454833019321;983.7245571240064;983.9803203935882;985.2364417576292;10554.677970846402;10807;10832.891671083345;10848.077913377294;10896.964788345094;598788;608609;623083;634272;636136;99322.5033874826;114868;138460.92308615378;140529;154119;846.9661213551512;991.1624677147798;1015.8527013582989;1025.2771795883884;1076.5855145768935;18625.627487449903;18664.81335186685;18840.43478695641;18857.24571017144;18874.678772485793;45354;55665.60835979133;57443.712781435825;57661;57699.71150144222;82855.3428931406;83374.58312708397;83417.66329346759;84776;85872;6798.864022719419;7887.84224315522;7920.881186782088;8156.510609363457;8172.18278172178;10969.725756856142;12325.198862073936;12388.62834114978;12438.37808109609;12727;9343.953280233556;10537.262391632512;10617.787644246917;10644.520996555018;10698.73253168677;10777.407242601732;10801.67594972152;10816.729581760204;10871.945640272064;11069.169812264274;10302.742431439274;10804.081653059264;10847.294925829796;11088.722781930517;11289.266197697123;7770.378369730295;7828.627845980619;7914.762557123296;7935.523868567904;7983.081945576333;7680.846383072196;8604.440711353742;9355.298352623717;10471.79056418852;10843.891561084289 03:01:37 INFO - PROCESS | 1659 | |3;mootools.html;1537.5156825599936;1592.4346856865811;1786.4908501077186;1895.459793958722;1960.7843137254908;7944.761657150295;8499.192576705203;8507.276881465075;8938.910610893898;8975.865362019567;8607.440516366447;8624.180702833237;8703.91296087039;8769.91230087699;8784.07767184446;1960.4902725291383;2343.777341152592;2382.5234953009467;2386.868722220277;2389.6773935518654;2332.825038122139;2343.0627748900492;2354.810820535627;2360;2379.095943541452;2333.288347054148;2373.1575290771784;2373.8219633527556;2376.394019525019;2392.3899405651537;2340.3914982104607;2354.540864531417;2367.2661474942824;2369.7393286738443;2370.727366352866;2202.856814307081;2229.030371788277;2239.6528538076623;2246.067881829039;2249.7750224977535;8670.263027642657;8725.869111963326;8778.122187781235;8783.824323513558;8802.471851688919;5145.716985565792;5306.70813105279;5307.336582927134;5309.389420216686;5320.308359913225;3145.182252614337;3353.02762198962;3519.419295816187;3540.6636369544854;3547.485614585896;978.2369751593768;993.602558976408;1006.8691070160905;1014.279861298479;1017.1303535477208;11294.491747871365;11429.914158154961;11450.770984580346;11498.655040348804;11510.884891151065;4610.861674149781;4680.9297860532115;4746.430428348551;4772.952270477251;4776.331313616097;9678.322517423712;9719.465429401309;9726.805463890683;9729.367591106697;9747.3664211826;5189.299444575009;5270.78916843322;5285.207218917193;5287.127623942083;5291.788328466818;4378.43080399546;4575.130725162208;4826.734529600835;4846.224604063333;4848.321235027099;3970.781607011584;3987.641112299907;4066.532348779869;4095.610916962884;4126.236646220459;1293.4567481657539;1308.4886955566335;1311.6917524381954;1321.8479874814327;1324.9536266230637;797.2704974947974;838.3712215838121;885.464294102059;893.1381217125505;919.9816003679889;5092.083424983539;5093.821716239914;5290.444503327172;5474.233607294982;5570.949977282788;372.86576832340336;420.3988296735697;432.51126227363505;433.6119173339843;442.91806015887164;3764.3600587900123;3764.5294338207723;3785.1483416231126;3822.9044273893373;3833.386658134685;7612.885806712904;8756.8686469703;10163.847542286872;10428.687139385831;10546.314489558308;10387.740306492398;10479.528421221063;10495.580176792995;10521.053105220566;10524.31591946521;6949.2008419031445;7671.156172820985;8091.878621820678;8118.391120665974;8168.877466838002;5636.239107720569;5713.314402271754;5735.7705691773535;5777.826665200051;5823.330317013598;9278.257739380699;9820.65627703027;9894.653687120915;9919.553620086968;9928.255380846322;9712.17446517054;9986.850197247193;9996.400215987063;10042.9957004299;10091.545880435546;6606.504512161703;7288.416926645961;7415.777526674208;7429.628518573942;7456.552606843607;8084.919150808417;8130.349572034342;8158.755237342889;8192.30365418946;8234;7205.279472052753;7285.599292038777;7335.77992660221;7463.440241981764;7476.25237476248;7186.784396468114;7251.601161936144;7253.1296244451005;7274.745383911538;7282.563046217244;6722.260551339251;6724.697388617622;6754.628495432798;6807.012983117321;6812.863742725217;4681.695689780154;4737.431508219036;4791.4729379767505;4861;4962.3052772611145;4577.931331030104;4779.617630589475;4856.660033797742;4857.732824694675;5067.265246539305 03:01:37 INFO - PROCESS | 1659 | |4;prototype.html;289.0720786276053;306.9033254524825;310.52644716806867;318.3839271010596;321.84229727433564;4769.499202583731;5640;5707.343655479621;5723.9713801430935;5745.649772303505;5589.441055894408;5598.188262701906;5660.915086273699;5669.886602267962;5672.773089076442;3551;3593.2454184621197;3683.9447408288897;3786.56454507731;4259.6805239607;3845.3270677631444;4190.539040705536;4206.915861682764;4220.662347012231;4270.316749320109;3887.8250478728382;4205.053862880858;4214.199302132601;4214.346776249687;4228.873133805991;3878.3212937736066;4174.283520348744;4228.661707063458;4239.215745087169;4251.532331443539;2840.750069969207;3216.9223310191114;3283.8850640227715;3362.125847279725;3703.6851867591017;4950.628702847301;5264.105102132647;5381.67709937405;5397.78408863645;5416.729163541847;4111.60939710727;4667.6732628716;4713.670043096985;4904.253617973946;4914.272578718809;5419.945800541984;5463.9180412293845;5850.795222167247;5867.149263356832;5903.822885313448;3949.545802232751;4070.715049946505;4086.5300490443683;4086.836526538935;4088.242055915955;5521.889562208733;5658.462446067658;5944.821655350346;5985.461308482258;6047.971844786397;3634.7278452541927;3639.9090022749115;3871.922561548753;4125.628693417607;4132.8140233689555;3778;3867.555231148454;4210.936835947463;4217.936730949038;4255.574442555781;4077.510698716173;4221.282381995068;4873.805047798049;4895.31465594817;4902.632302577321;4655.743934083658;5485.780568777204;5559.6664200148125;5946.464818166386;6068.33248342682;1246.5200897654288;1372.0807059270114;1390.727484351827;1399.6640806206437;1405.1498843199922;735.9365716539656;759.1194214710908;792.5324058805332;856.5631527920681;916.9174774270348;4383.684894531628;4678.590525879175;5169.327987361619;5240.764165612557;5493.395726470085;5925.059835209538;6057.182280392179;6070.484008859296;6072.210612620331;6113.694315284218;4507.504174540798;4698.389209402824;4815.855524334205;4872.076753947603;5338.572914166857;2837.5743638454396;3035.301880567482;3149.4882456420587;3171.4935405682118;3175.3173067790535;5819.992102724638;6065.727042283108;6313.305536390993;6315.2742329419025;6322.841928951721;5893.970530147321;6307.00417301778;6315.179026726588;6321;6334.271558770801;5572.972135139379;6152.876942461216;6210.782622608187;6247.437730604267;6501.4148726613885;5367;5629.296337957755;5705.286839145107;5964.793026580193;6024.222854257917;5269.789208431773;5285.339332583427;5828.795992140254;6101.84745381369;6102.877942441038;5589.888202236014;6000.789972351122;6122.938770612415;6161.722722477589;6537.673116344259;4100.466939297872;4117.197146556513;4139.8965025874595;4148.23257697327;4148.937765933574;5176.585873130216;5615.8876822464135;5901.852453688521;6082.330943596113;6103.511719062553;4995.550400463976;5173.379194496684;5232.659877107976;5622.522085622604;5873.970630146822;4705.388299521041;4713.700409713146;5842.591018628656;5903;5931.288245410578;5018.573421259234;5031.270465782514;5095.515925987025;5476.835694929158;5768.884622307446;5046.823361182341;5903.675297858659;5947.613405128653;5974.820755377345;5993.880122397441;5156.871078223075;5822.592418530662;5825.94174058254;5848.385919478564;6044.760824031151 03:01:37 INFO - PROCESS | 1659 | |5;yui.html;670.3095811314348;694.6873906741968;721.1958666087314;730.1019745712773;753.5441058159813;15088.77366839497;15367.0011449256;15424.69150616987;15557.377704891791;15583.22083895805;14385.78421323681;14423.711525769479;14425.206613636272;14445.927770361133;14469.710605787877;885.7918389178554;906.5920335848862;908.2824568590805;918.0589895357245;920.0707285641516;876.369014309696;897.4660077254024;901.0673952459206;909.0409617853162;911.3165126155384;961.7884065505577;961.8412961861319;962.4129281138535;965.6764983730459;967.2648786921955;933.3233405780823;953.9713808585753;957.7509847439646;959.5394210778866;961.0149596663406;698.0332239847796;715.6493318274008;718.195620904585;718.4288490649946;719.151401346411;15067.698646027018;15130.243487825564;15248.466303679428;15257.160856152906;15273.236338183046;12194.878051219463;12265.632031039084;12341.876581234163;12552.309622970733;12942;9684.682450728511;9696.612135514571;9965.302428829984;10376.740581485523;10417.687469375931;3408.931821363559;3422.1476310118683;3427.73539554814;3474.1662001119803;3540.8760693375607;43322.700318990166;43667.78166109181;43727.344089838676;49468.75265623685;77320;8969.50667713269;9125.224355929688;9277.396969197116;9292.349535532583;9430.481323527272;21284.893575532333;21329;21363.14547418117;21364.893175534024;21503.892480537495;6902.413294869892;6905.723771049102;6926.134233220848;6928.203256625452;7231.385332246818;3911.963329717627;3971.344728119828;4035.9910022494378;4087.693422993287;4102.548719640837;2721.4557088582364;3250.967490325114;3350.1289664687356;3483.4600636901323;3548.556430446194;521.3795583255913;562.6539678098022;620.9223847019123;636.1221514310222;641.0544446940725;484.57357525377444;547.9835604931858;569.888717001848;595.3213336796056;609.135028259872;7040.190378106481;7054.717811287594;7112.359887610037;7658.24173632028;8073.233042860918;517.5316338713395;582.9941700583024;588.2969851027981;591.8579540910237;602.208096353294;3682.4660424238336;3825.5409350877517;3868.303705333011;3896.4544963705102;3904.2777086239134;78623.60688196523;81004.59497702474;81187.59406202931;87637;88515;76950.84573731398;77183;77729;79096.29940706518;82373.76439353415;38706.80646596805;48581.75709121432;52165.7391713039;68615.31384686189;71315.64342178359;6515.153030106056;7034.437245020284;7472.738454154079;7963.721269755531;8406.579671016547;44869.55130448654;45553.29428916892;46059.53940460552;46093;46429.767851160526;60961.085583715394;61694;62179;62376.688116559126;63439.682801585695;10284;10842.728931776768;11289.943550282525;11319.71700707456;11515;10818.837717434088;11580.031394663096;11839.704007399883;12155.513779448724;12640.178388404724;11088.223824332219;11335.886641133484;11720.472578734149;11746.17776755619;11850.76298474043;11025.944870275598;11308.378039207922;11345.773084538429;11347.886521134684;11895.405229738652;10630.8936910633;10930.125589952626;10984.340939543654;11071.612493563009;11128.721781955515;7681.308682218627;9679.467629280458;9684.85472717924;9712.417254964725;9897.406155630686;9462.959074501938;9583.760405989906;9617.471039092638;9645.131938125602;9858.260630452889 03:01:37 INFO - PROCESS | 1659 | __end_tp_report 03:01:37 INFO - PROCESS | 1659 | __start_cc_report 03:01:37 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,419 03:01:37 INFO - PROCESS | 1659 | __end_cc_report 03:01:37 INFO - PROCESS | 1659 | __startTimestamp1464256897822__endTimestamp 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | ------- Summary: start ------- 03:01:37 INFO - PROCESS | 1659 | Number of tests: 6 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5762.28 Median:6083.60 stddev:792.93 (13.0%) stddev-sans-first:775.28 03:01:37 INFO - PROCESS | 1659 | Values: 3501.5 3509.7 3533.5 3540.1 3545.9 4987.3 5743.0 5760.1 5800.8 5906.0 4585.3 5775.7 5786.5 5821.7 5851.9 5157.8 5211.8 5284.3 5308.5 5351.8 91.9 5189.7 5233.6 5305.4 5310.0 5250.7 5252.9 5279.6 5281.2 5372.3 5293.7 5318.5 5327.8 5366.2 5381.1 5149.3 5170.7 5175.6 5234.6 5283.7 5614.1 5726.0 5731.1 5769.0 5794.7 6209.3 6231.3 6244.7 6261.9 6266.0 6151.9 6187.6 6205.8 6227.0 6318.3 5972.4 5974.8 5979.7 6001.8 6044.0 5293.1 6254.4 6430.0 6456.0 6457.3 6142.0 6145.9 6180.0 6231.9 6246.3 6258.5 6281.6 6316.6 6324.7 6352.5 4843.3 5541.8 6240.5 6249.9 6267.9 6164.1 6205.7 6210.8 6227.1 6231.3 4943.5 4945.1 4972.0 4973.3 4983.0 4396.6 4423.1 4437.3 4495.3 4502.6 5780.6 5845.6 5930.1 5931.3 5949.9 6293.5 6300.7 6343.9 6348.9 6352.6 5819.0 5921.9 5954.8 5993.6 6074.3 5506.3 5512.5 5514.4 5518.2 5530.2 5305.5 6578.7 6594.0 6596.8 6611.0 6163.9 6213.9 6245.1 6296.3 6370.3 6515.2 6580.0 6582.2 6602.1 6615.7 6181.3 6239.4 6245.7 6256.8 6277.3 6143.5 6164.5 6189.3 6270.5 6293.6 6169.6 6179.5 6211.2 6215.1 6225.8 4752.7 6162.5 6194.2 6212.9 6214.2 5907.9 6007.5 6054.7 6083.6 6115.5 6159.1 6191.1 6215.9 6224.9 6240.4 6101.5 6142.3 6152.2 6156.9 6162.5 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:68248.52 Median:40462.10 stddev:152343.82 (376.5%) stddev-sans-first:152686.24 03:01:37 INFO - PROCESS | 1659 | Values: 489.7 512.9 530.8 533.6 573.9 41234.6 43671.3 43689.8 43764.1 43852.0 44384.8 44904.0 45324.0 45325.0 45554.8 9486.8 9610.8 9629.3 9665.0 9681.7 9654.2 9741.0 9745.2 9773.7 9778.7 9760.6 9780.4 9794.3 9799.2 9809.2 9740.2 9748.4 9763.1 9794.6 9810.8 9524.6 9618.0 9623.0 9627.6 9654.8 41630.4 41773.6 42123.8 42214.4 42217.0 2739.2 3016.5 3071.0 3278.2 3291.2 27445.9 28532.9 29564.1 30302.4 30341.6 2664.6 2682.7 2734.5 2750.0 2768.1 459595.0 463073.0 467058.0 468470.0 468781.0 34922.0 35310.9 35754.6 35770.3 36266.8 36705.6 37182.4 37251.0 37256.3 37321.8 15193.4 15334.4 15340.0 15383.2 15410.0 12529.8 12805.9 12835.0 12841.9 12856.4 7855.8 7880.5 7919.8 8007.9 8051.0 2793.3 2804.2 3279.3 3308.9 3324.6 847.8 973.9 981.7 1039.4 1143.3 25462.5 28262.4 30377.8 30392.4 30675.4 903.2 1050.7 1072.9 1096.5 1142.9 15583.0 15625.3 15742.8 15792.5 15939.8 645135.0 676592.0 710864.0 715477.0 722563.0 51762.0 52018.7 65315.3 65423.3 65661.3 1408.2 1424.7 1429.2 1444.7 1450.6 29593.6 29808.4 30004.7 30462.8 30469.8 56628.4 56668.4 56750.4 56862.7 56890.4 518072.0 519452.0 519751.0 521410.0 521913.0 26627.9 27837.3 27918.0 28035.6 28116.0 48190.8 48576.5 48683.0 49144.5 50374.5 39913.2 40063.6 40513.6 40800.6 41661.0 39071.0 39266.0 39860.8 40434.4 40539.0 38102.0 38873.2 40354.2 40425.6 40489.8 31499.5 31761.7 33642.3 34584.0 35262.0 24967.3 25543.5 25957.8 26125.3 26128.9 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35635.41 Median:25006.94 stddev:103404.99 (413.5%) stddev-sans-first:103661.09 03:01:37 INFO - PROCESS | 1659 | Values: 333.2 341.8 379.4 385.8 400.7 19004.2 20208.4 20449.4 20650.4 21115.7 20446.0 20741.7 20788.8 20884.0 21249.4 5632.2 5789.2 5991.3 6022.1 6032.5 5852.7 5924.1 5990.8 5996.5 6004.5 5566.9 5898.0 5901.8 5914.3 5930.0 5651.6 5708.9 5898.7 5903.1 5904.5 4273.5 4676.3 4724.5 4974.5 5113.8 24336.9 24469.1 24746.9 24982.0 25031.9 1917.7 2274.3 2303.7 2364.9 2782.2 12517.5 14327.8 15015.9 15038.4 15293.0 1492.8 1513.0 1763.6 1784.0 1786.5 109208.0 111246.0 111535.0 114556.0 114741.0 8919.5 10407.7 10585.1 10959.2 11095.6 13258.5 13290.6 13424.9 13438.3 13533.7 8263.3 8388.4 8426.1 8450.9 8731.8 8337.4 8576.5 8719.0 8741.0 8872.4 5703.3 6580.2 6690.6 6698.2 6765.1 1556.9 1573.2 1756.4 1880.9 1881.9 885.6 906.4 920.5 939.0 1047.2 13674.8 17251.1 18012.8 21439.4 21539.7 883.4 981.4 983.7 984.0 985.2 10554.7 10807.0 10832.9 10848.1 10897.0 598788.0 608609.0 623083.0 634272.0 636136.0 99322.5 114868.0 138460.9 140529.0 154119.0 847.0 991.2 1015.9 1025.3 1076.6 18625.6 18664.8 18840.4 18857.2 18874.7 45354.0 55665.6 57443.7 57661.0 57699.7 82855.3 83374.6 83417.7 84776.0 85872.0 6798.9 7887.8 7920.9 8156.5 8172.2 10969.7 12325.2 12388.6 12438.4 12727.0 9344.0 10537.3 10617.8 10644.5 10698.7 10777.4 10801.7 10816.7 10871.9 11069.2 10302.7 10804.1 10847.3 11088.7 11289.3 7770.4 7828.6 7914.8 7935.5 7983.1 7680.8 8604.4 9355.3 10471.8 10843.9 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5564.64 Median:4911.65 stddev:3122.36 (63.6%) stddev-sans-first:3116.45 03:01:37 INFO - PROCESS | 1659 | Values: 1537.5 1592.4 1786.5 1895.5 1960.8 7944.8 8499.2 8507.3 8938.9 8975.9 8607.4 8624.2 8703.9 8769.9 8784.1 1960.5 2343.8 2382.5 2386.9 2389.7 2332.8 2343.1 2354.8 2360.0 2379.1 2333.3 2373.2 2373.8 2376.4 2392.4 2340.4 2354.5 2367.3 2369.7 2370.7 2202.9 2229.0 2239.7 2246.1 2249.8 8670.3 8725.9 8778.1 8783.8 8802.5 5145.7 5306.7 5307.3 5309.4 5320.3 3145.2 3353.0 3519.4 3540.7 3547.5 978.2 993.6 1006.9 1014.3 1017.1 11294.5 11429.9 11450.8 11498.7 11510.9 4610.9 4680.9 4746.4 4773.0 4776.3 9678.3 9719.5 9726.8 9729.4 9747.4 5189.3 5270.8 5285.2 5287.1 5291.8 4378.4 4575.1 4826.7 4846.2 4848.3 3970.8 3987.6 4066.5 4095.6 4126.2 1293.5 1308.5 1311.7 1321.8 1325.0 797.3 838.4 885.5 893.1 920.0 5092.1 5093.8 5290.4 5474.2 5570.9 372.9 420.4 432.5 433.6 442.9 3764.4 3764.5 3785.1 3822.9 3833.4 7612.9 8756.9 10163.8 10428.7 10546.3 10387.7 10479.5 10495.6 10521.1 10524.3 6949.2 7671.2 8091.9 8118.4 8168.9 5636.2 5713.3 5735.8 5777.8 5823.3 9278.3 9820.7 9894.7 9919.6 9928.3 9712.2 9986.9 9996.4 10043.0 10091.5 6606.5 7288.4 7415.8 7429.6 7456.6 8084.9 8130.3 8158.8 8192.3 8234.0 7205.3 7285.6 7335.8 7463.4 7476.3 7186.8 7251.6 7253.1 7274.7 7282.6 6722.3 6724.7 6754.6 6807.0 6812.9 4681.7 4737.4 4791.5 4861.0 4962.3 4577.9 4779.6 4856.7 4857.7 5067.3 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4702.81 Median:5236.71 stddev:1480.69 (28.3%) stddev-sans-first:1447.31 03:01:37 INFO - PROCESS | 1659 | Values: 289.1 306.9 310.5 318.4 321.8 4769.5 5640.0 5707.3 5724.0 5745.6 5589.4 5598.2 5660.9 5669.9 5672.8 3551.0 3593.2 3683.9 3786.6 4259.7 3845.3 4190.5 4206.9 4220.7 4270.3 3887.8 4205.1 4214.2 4214.3 4228.9 3878.3 4174.3 4228.7 4239.2 4251.5 2840.8 3216.9 3283.9 3362.1 3703.7 4950.6 5264.1 5381.7 5397.8 5416.7 4111.6 4667.7 4713.7 4904.3 4914.3 5419.9 5463.9 5850.8 5867.1 5903.8 3949.5 4070.7 4086.5 4086.8 4088.2 5521.9 5658.5 5944.8 5985.5 6048.0 3634.7 3639.9 3871.9 4125.6 4132.8 3778.0 3867.6 4210.9 4217.9 4255.6 4077.5 4221.3 4873.8 4895.3 4902.6 4655.7 5485.8 5559.7 5946.5 6068.3 1246.5 1372.1 1390.7 1399.7 1405.1 735.9 759.1 792.5 856.6 916.9 4383.7 4678.6 5169.3 5240.8 5493.4 5925.1 6057.2 6070.5 6072.2 6113.7 4507.5 4698.4 4815.9 4872.1 5338.6 2837.6 3035.3 3149.5 3171.5 3175.3 5820.0 6065.7 6313.3 6315.3 6322.8 5894.0 6307.0 6315.2 6321.0 6334.3 5573.0 6152.9 6210.8 6247.4 6501.4 5367.0 5629.3 5705.3 5964.8 6024.2 5269.8 5285.3 5828.8 6101.8 6102.9 5589.9 6000.8 6122.9 6161.7 6537.7 4100.5 4117.2 4139.9 4148.2 4148.9 5176.6 5615.9 5901.9 6082.3 6103.5 4995.6 5173.4 5232.7 5622.5 5874.0 4705.4 4713.7 5842.6 5903.0 5931.3 5018.6 5031.3 5095.5 5476.8 5768.9 5046.8 5903.7 5947.6 5974.8 5993.9 5156.9 5822.6 5825.9 5848.4 6044.8 03:01:37 INFO - PROCESS | 1659 | 03:01:37 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:16513.69 Median:585.65 stddev:22374.26 (3820.4%) stddev-sans-first:22405.40 03:01:37 INFO - PROCESS | 1659 | Values: 670.3 694.7 721.2 730.1 753.5 15088.8 15367.0 15424.7 15557.4 15583.2 14385.8 14423.7 14425.2 14445.9 14469.7 885.8 906.6 908.3 918.1 920.1 876.4 897.5 901.1 909.0 911.3 961.8 961.8 962.4 965.7 967.3 933.3 954.0 957.8 959.5 961.0 698.0 715.6 718.2 718.4 719.2 15067.7 15130.2 15248.5 15257.2 15273.2 12194.9 12265.6 12341.9 12552.3 12942.0 9684.7 9696.6 9965.3 10376.7 10417.7 3408.9 3422.1 3427.7 3474.2 3540.9 43322.7 43667.8 43727.3 49468.8 77320.0 8969.5 9125.2 9277.4 9292.3 9430.5 21284.9 21329.0 21363.1 21364.9 21503.9 6902.4 6905.7 6926.1 6928.2 7231.4 3912.0 3971.3 4036.0 4087.7 4102.5 2721.5 3251.0 3350.1 3483.5 3548.6 521.4 562.7 620.9 636.1 641.1 484.6 548.0 569.9 595.3 609.1 7040.2 7054.7 7112.4 7658.2 8073.2 517.5 583.0 588.3 591.9 602.2 3682.5 3825.5 3868.3 3896.5 3904.3 78623.6 81004.6 81187.6 87637.0 88515.0 76950.8 77183.0 77729.0 79096.3 82373.8 38706.8 48581.8 52165.7 68615.3 71315.6 6515.2 7034.4 7472.7 7963.7 8406.6 44869.6 45553.3 46059.5 46093.0 46429.8 60961.1 61694.0 62179.0 62376.7 63439.7 10284.0 10842.7 11289.9 11319.7 11515.0 10818.8 11580.0 11839.7 12155.5 12640.2 11088.2 11335.9 11720.5 11746.2 11850.8 11025.9 11308.4 11345.8 11347.9 11895.4 10630.9 10930.1 10984.3 11071.6 11128.7 7681.3 9679.5 9684.9 9712.4 9897.4 9463.0 9583.8 9617.5 9645.1 9858.3 03:01:37 INFO - PROCESS | 1659 | -------- Summary: end -------- 03:01:37 INFO - PROCESS | 1659 | 03:01:38 INFO - TEST-INFO | 1659: exit 0 03:01:38 INFO - TEST-OK | dromaeo_css | took 1112210ms 03:01:38 INFO - TEST-START | kraken 03:01:38 INFO - operating with platform_type : mac_ 03:01:38 INFO - Initialising browser for kraken test... 03:01:38 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpxXUYj_/profile http://localhost:49278/getInfo.html) 03:01:41 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 03:01:41 INFO - PROCESS | 1671 | colorDepth:24 03:01:41 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 03:01:41 INFO - PROCESS | 1671 | __metrics 03:01:41 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 03:01:42 INFO - TEST-INFO | 1671: exit 0 03:01:42 INFO - Browser initialized. 03:01:42 INFO - Running cycle 1/1 for kraken test... 03:01:42 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/T/tmpxXUYj_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:54 INFO - PROCESS | 1673 | RSS: Main: 252432384 03:01:54 INFO - PROCESS | 1673 | 03:01:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 03:01:57 INFO - PROCESS | 1673 | RSS: Main: 264060928 03:01:57 INFO - PROCESS | 1673 | 03:02:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 03:02:01 INFO - PROCESS | 1673 | RSS: Main: 268922880 03:02:01 INFO - PROCESS | 1673 | 03:02:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 03:02:05 INFO - PROCESS | 1673 | RSS: Main: 269185024 03:02:05 INFO - PROCESS | 1673 | 03:02:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 03:02:07 INFO - PROCESS | 1673 | RSS: Main: 269762560 03:02:07 INFO - PROCESS | 1673 | 03:02:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:02:10 INFO - PROCESS | 1673 | RSS: Main: 270307328 03:02:10 INFO - PROCESS | 1673 | 03:02:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 03:02:13 INFO - PROCESS | 1673 | RSS: Main: 270544896 03:02:13 INFO - PROCESS | 1673 | 03:02:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 03:02:17 INFO - PROCESS | 1673 | RSS: Main: 270942208 03:02:17 INFO - PROCESS | 1673 | 03:02:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 03:02:21 INFO - PROCESS | 1673 | RSS: Main: 271249408 03:02:21 INFO - PROCESS | 1673 | 03:02:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:02:23 INFO - PROCESS | 1673 | RSS: Main: 270589952 03:02:23 INFO - PROCESS | 1673 | 03:02:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:02:25 INFO - PROCESS | 1673 | RSS: Main: 270544896 03:02:25 INFO - PROCESS | 1673 | 03:02:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:02:28 INFO - PROCESS | 1673 | RSS: Main: 270966784 03:02:28 INFO - PROCESS | 1673 | 03:02:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:02:32 INFO - PROCESS | 1673 | RSS: Main: 271052800 03:02:32 INFO - PROCESS | 1673 | 03:02:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:02:35 INFO - PROCESS | 1673 | RSS: Main: 271396864 03:02:35 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 03:02:37 INFO - PROCESS | 1673 | RSS: Main: 272912384 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | __start_tp_report 03:02:37 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 03:02:37 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 03:02:37 INFO - PROCESS | 1673 | |i|pagename|runs| 03:02:37 INFO - PROCESS | 1673 | |0;ai-astar;110;108;116;114;134;172;171;117;115;115 03:02:37 INFO - PROCESS | 1673 | |1;audio-beat-detection;151;119;155;200;131;141;140;126;169;119 03:02:37 INFO - PROCESS | 1673 | |2;audio-dft;273;258;219;212;192;197;199;199;193;199 03:02:37 INFO - PROCESS | 1673 | |3;audio-fft;72;80;76;163;70;58;57;60;127;52 03:02:37 INFO - PROCESS | 1673 | |4;audio-oscillator;70;103;158;100;100;84;123;96;106;113 03:02:37 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;86;88;85;88;85;88;86;110;86 03:02:37 INFO - PROCESS | 1673 | |6;imaging-darkroom;94;119;151;95;137;95;95;95;99;95 03:02:37 INFO - PROCESS | 1673 | |7;imaging-desaturate;151;137;90;91;90;92;91;90;90;91 03:02:37 INFO - PROCESS | 1673 | |8;json-parse-financial;57;61;60;59;60;63;58;73;61;55 03:02:37 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;70;69;70;99;74;84;107;101;105;84 03:02:37 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;88;87;89;89;87;86;90;89;88;85 03:02:37 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;153;160;131;147;153;146;164;156;200;141 03:02:37 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;193;175;170;177;160;181;167;225;222;151 03:02:37 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;68;66;70;68;77;87;84;87;86 03:02:37 INFO - PROCESS | 1673 | __end_tp_report 03:02:37 INFO - PROCESS | 1673 | __start_cc_report 03:02:37 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1075 03:02:37 INFO - PROCESS | 1673 | __end_cc_report 03:02:37 INFO - PROCESS | 1673 | __startTimestamp1464256957979__endTimestamp 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | ------- Summary: start ------- 03:02:37 INFO - PROCESS | 1673 | Number of tests: 14 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:127.20 Median:116.50 stddev:24.35 (20.9%) stddev-sans-first:25.02 03:02:37 INFO - PROCESS | 1673 | Values: 110.0 108.0 116.0 114.0 134.0 172.0 171.0 117.0 115.0 115.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:146.00 stddev:25.14 (17.2%) stddev-sans-first:26.57 03:02:37 INFO - PROCESS | 1673 | Values: 151.0 119.0 155.0 200.0 131.0 141.0 140.0 126.0 169.0 119.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:214.10 Median:205.50 stddev:28.55 (13.9%) stddev-sans-first:20.86 03:02:37 INFO - PROCESS | 1673 | Values: 273.0 258.0 219.0 212.0 192.0 197.0 199.0 199.0 193.0 199.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:81.50 Median:65.00 stddev:35.67 (54.9%) stddev-sans-first:37.67 03:02:37 INFO - PROCESS | 1673 | Values: 72.0 80.0 76.0 163.0 70.0 58.0 57.0 60.0 127.0 52.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:105.30 Median:140.50 stddev:23.57 (16.8%) stddev-sans-first:21.25 03:02:37 INFO - PROCESS | 1673 | Values: 70.0 103.0 158.0 100.0 100.0 84.0 123.0 96.0 106.0 113.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:87.00 stddev:7.48 (8.6%) stddev-sans-first:7.93 03:02:37 INFO - PROCESS | 1673 | Values: 88.0 86.0 88.0 85.0 88.0 85.0 88.0 86.0 110.0 86.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:95.00 stddev:20.90 (22.0%) stddev-sans-first:21.59 03:02:37 INFO - PROCESS | 1673 | Values: 94.0 119.0 151.0 95.0 137.0 95.0 95.0 95.0 99.0 95.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:90.50 stddev:22.75 (25.1%) stddev-sans-first:15.47 03:02:37 INFO - PROCESS | 1673 | Values: 151.0 137.0 90.0 91.0 90.0 92.0 91.0 90.0 90.0 91.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:60.50 stddev:4.88 (8.1%) stddev-sans-first:4.99 03:02:37 INFO - PROCESS | 1673 | Values: 57.0 61.0 60.0 59.0 60.0 63.0 58.0 73.0 61.0 55.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:86.30 Median:72.00 stddev:15.45 (21.5%) stddev-sans-first:15.22 03:02:37 INFO - PROCESS | 1673 | Values: 70.0 69.0 70.0 99.0 74.0 84.0 107.0 101.0 105.0 84.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:88.50 stddev:1.55 (1.8%) stddev-sans-first:1.64 03:02:37 INFO - PROCESS | 1673 | Values: 88.0 87.0 89.0 89.0 87.0 86.0 90.0 89.0 88.0 85.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:154.50 stddev:18.43 (11.9%) stddev-sans-first:19.53 03:02:37 INFO - PROCESS | 1673 | Values: 153.0 160.0 131.0 147.0 153.0 146.0 164.0 156.0 200.0 141.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:179.00 stddev:24.63 (13.8%) stddev-sans-first:25.80 03:02:37 INFO - PROCESS | 1673 | Values: 193.0 175.0 170.0 177.0 160.0 181.0 167.0 225.0 222.0 151.0 03:02:37 INFO - PROCESS | 1673 | 03:02:37 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:80.50 stddev:9.04 (11.2%) stddev-sans-first:9.10 03:02:37 INFO - PROCESS | 1673 | Values: 68.0 68.0 66.0 70.0 68.0 77.0 87.0 84.0 87.0 86.0 03:02:37 INFO - PROCESS | 1673 | -------- Summary: end -------- 03:02:37 INFO - PROCESS | 1673 | 03:02:38 INFO - TEST-INFO | 1673: exit 0 03:02:38 INFO - TEST-OK | kraken | took 60410ms 03:02:38 INFO - SUITE-END | took 1172s 03:02:39 INFO - Completed test suite (00:19:32) 03:02:39 INFO - javascript benchmark 03:02:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7069.3744257899125, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3501.457274122512, 3509.684128428441, 3533.4699795030747, 3540.0795793093803, 3545.92908141837, 4987.301777751113, 5743.0, 5760.107183386572, 5800.767969281234, 5906.0, 4585.312203169527, 5775.682337471438, 5786.450287222718, 5821.708914554277, 5851.941480585193, 5157.76790044448, 5211.817586384477, 5284.2866213061325, 5308.54877335425, 5351.812686555972, 91.9485327044213, 5189.662671926331, 5233.607479439038, 5305.363356397238, 5310.0441920454305, 5250.737463126848, 5252.921206181911, 5279.60402969775, 5281.181416880389, 5372.328458942632, 5293.735313234323, 5318.494742999409, 5327.813526526592, 5366.195070739382, 5381.13901775714, 5149.2791009258735, 5170.741462926839, 5175.560077393426, 5234.555062819665, 5283.682979021271, 5614.101743721026, 5726.0, 5731.140328950691, 5768.97115514424, 5794.65232086072, 6209.285932117819, 6231.345708700612, 6244.718987645567, 6261.906071408933, 6265.968670156665, 6151.907721384183, 6187.597806142587, 6205.844853878644, 6227.034809604518, 6318.336574659686, 5972.372900845436, 5974.820755377345, 5979.671118088503, 6001.849953751192, 6043.969780151071, 5293.12663410533, 6254.437100660963, 6430.0, 6455.967720161463, 6457.321981192001, 6142.038385241494, 6145.907811382833, 6180.0, 6231.937680623227, 6246.250449945945, 6258.530610204253, 6281.623102613858, 6316.557840951089, 6324.747010119544, 6352.460040896483, 4843.297721830385, 5541.778328866881, 6240.500759939194, 6249.875002499924, 6267.9373206268265, 6164.075388691643, 6205.6586887720705, 6210.7515699372425, 6227.09707092469, 6231.2522497300615, 4943.456219815817, 4945.109880221523, 4971.975140124277, 4973.328600638867, 4982.975085124551, 4396.648268138541, 4423.071155057474, 4437.33439984005, 4495.280755079237, 4502.57225563571, 5780.624259423124, 5845.561582881301, 5930.080837470199, 5931.288245410492, 5949.881002379928, 6293.527985401113, 6300.684965751694, 6343.904841427382, 6348.936510634834, 6352.587081839665, 5819.0, 5921.851953701192, 5954.791582294599, 5993.640381577119, 6074.331823499411, 5506.284183056177, 5512.503874651301, 5514.393416724157, 5518.172274158828, 5530.225768392428, 5305.495977882095, 6578.703958321887, 6594.0, 6596.80209593713, 6611.0, 6163.907541386972, 6213.937860621336, 6245.094461303176, 6296.307406185224, 6370.331115233019, 6515.218173819172, 6579.967100164468, 6582.210134783857, 6602.10871532337, 6615.735370585123, 6181.320054794059, 6239.438450539473, 6245.718942647501, 6256.7810126645345, 6277.2781130170615, 6143.450468055707, 6164.506839452923, 6189.257289125334, 6270.49836013127, 6293.559450838397, 6169.598976066542, 6179.474744646755, 6211.16149319836, 6215.098810672517, 6225.844353891189, 4752.738599377068, 6162.476189523941, 6194.225721784777, 6212.937870621417, 6214.1921550199995, 5907.852303692442, 6007.519398448201, 6054.727537260922, 6083.6045657032155, 6115.480184184395, 6159.1069294951085, 6191.117092866828, 6215.906761398493, 6224.937750622617, 6240.438360547572, 6101.542384321105, 6142.32434432221, 6152.200213972155, 6156.938430615636, 6162.537809664203], "value": 5716.694991421692, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.72085911030706, 512.9128048231801, 530.7903378165624, 533.6024661627089, 573.9339975902773, 41234.58765412349, 43671.344929826, 43689.78155109228, 43764.124717505634, 43852.0, 44384.77807610957, 44904.0, 45324.0, 45325.0, 45554.772226138826, 9486.762830929212, 9610.807783844319, 9629.27780416468, 9664.985176556465, 9681.70954871355, 9654.179394751454, 9741.0, 9745.171660408874, 9773.706788796311, 9778.706638800848, 9760.560774765117, 9780.413175209475, 9794.265430092735, 9799.16707079899, 9809.166220871235, 9740.220782337432, 9748.36635618686, 9763.121319081245, 9794.559244834, 9810.754731131707, 9524.619015239383, 9618.0, 9622.951885240529, 9627.56675949584, 9654.75863103421, 41630.375544366856, 41773.582264177276, 42123.789381053204, 42214.366784498256, 42217.0, 2739.2467071555284, 3016.457037733207, 3070.9846450767604, 3278.164068162603, 3291.226561757985, 27445.90216391342, 28532.857335713397, 29564.113076607737, 30302.393952121056, 30341.634626441864, 2664.61363102351, 2682.7183145769804, 2734.4667789781, 2750.037486879596, 2768.1280396675184, 459595.0, 463073.0, 467058.0, 468470.0, 468780.99999999657, 34922.0, 35310.940671779885, 35754.64245357513, 35770.28459430797, 36266.8186659065, 36705.63294367075, 37182.44226336607, 37251.0, 37256.254874902894, 37321.813390933414, 15193.392264309305, 15334.386624535116, 15340.0, 15383.153926533923, 15410.0, 12529.812052819216, 12805.871941280655, 12835.0, 12841.93579032099, 12856.357182141042, 7855.842883142305, 7880.527168369801, 7919.841603167904, 8007.919920800718, 8051.0, 2793.25978615667, 2804.2008027712004, 3279.2785587170783, 3308.9007329780147, 3324.6176689680515, 847.8007668197969, 973.9464329461948, 981.7054883534911, 1039.4231201683137, 1143.2911594811271, 25462.49075018526, 28262.43475130486, 30377.848110759747, 30392.392152156834, 30675.38649227003, 903.1781079218011, 1050.695298363466, 1072.9088027517591, 1096.4572536594437, 1142.874283828778, 15583.0, 15625.296861641253, 15742.842571574367, 15792.526224213292, 15939.760903586455, 645135.0, 676592.0, 710864.0, 715477.0, 722563.0, 51762.0, 52018.73990630022, 65315.34684653188, 65423.345766542676, 65661.34338656552, 1408.239550642662, 1424.7150569885857, 1429.2139323372103, 1444.7471692453778, 1450.5938337265582, 29593.556096658136, 29808.403831923675, 30004.699953001065, 30462.84768576143, 30469.847650761603, 56628.43371566397, 56668.433315667964, 56750.43249567616, 56862.7156864213, 56890.43109568852, 518072.0, 519452.0, 519751.0, 521410.0, 521913.0, 26627.866860665574, 27837.304067398476, 27918.0, 28035.579466307612, 28116.0, 48190.75904620454, 48576.514234858616, 48683.0, 49144.508554915425, 50374.49625503698, 39913.2017359657, 40063.59936400599, 40513.59486405098, 40800.59199407968, 41661.0, 39071.023224419616, 39266.036698165546, 39860.80069599633, 40434.39348409835, 40539.0, 38102.0, 38873.222535548564, 40354.19291614093, 40425.595744042184, 40489.79755101206, 31499.52750708787, 31761.682383175874, 33642.3271534563, 34583.96248112561, 35262.0, 24967.250982470556, 25543.489130217662, 25957.82388784934, 26125.346866328495, 26128.8693556531], "value": 18612.729383432885, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.2235890375425, 341.84446077034954, 379.41380567023964, 385.7646835430387, 400.6854619123988, 19004.239830406783, 20208.39374818754, 20449.386518404433, 20650.380488585328, 21115.683264751042, 20445.977701114924, 20741.688874666856, 20788.792112078874, 20884.0, 21249.36251912445, 5632.239647647556, 5789.218455508494, 5991.281046274453, 6022.126791615213, 6032.457078862902, 5852.67810270435, 5924.111383292519, 5990.790322338719, 5996.520278377741, 6004.549658775588, 5566.888662226753, 5898.0, 5901.793437229698, 5914.3494215636465, 5929.970350148244, 5651.604387692863, 5708.914366284509, 5898.705064746789, 5903.055511118222, 5904.498117660004, 4273.50854651716, 4676.3453116563705, 4724.535394027828, 4974.502549745032, 5113.769880355393, 24336.9048392821, 24469.143579974614, 24746.88639011249, 24982.0, 25031.873565689588, 1917.6548221320156, 2274.3397319956357, 2303.67748515208, 2364.9172278970327, 2782.1514438096374, 12517.499300027988, 14327.78508322376, 15015.924920375437, 15038.398464061534, 15293.0, 1492.7611582146912, 1513.0, 1763.5855573940114, 1784.0, 1786.499340553941, 109208.0, 111246.0, 111535.0, 114556.0, 114741.0, 8919.464832110094, 10407.739806504898, 10585.100266477282, 10959.178061645249, 11095.5561777528, 13258.535951241853, 13290.601281961557, 13424.93287533556, 13438.260895650834, 13533.729325413437, 8263.297619702273, 8388.412811103164, 8426.115257897955, 8450.87323690145, 8731.781705457288, 8337.416380853403, 8576.528290943934, 8719.0, 8741.0, 8872.423292485968, 5703.344115426779, 6580.177477815273, 6690.565113267622, 6698.22970358415, 6765.1205343305055, 1556.8598826105706, 1573.2448424755953, 1756.3589289909091, 1880.868339216269, 1881.89649569275, 885.583775625455, 906.4470672889532, 920.5489310237936, 938.9765255868658, 1047.1831971062584, 13674.794878076837, 17251.05119218455, 18012.819871801377, 21439.392045596644, 21539.67690484644, 883.3992884838372, 981.4454833019321, 983.7245571240064, 983.9803203935882, 985.2364417576292, 10554.677970846402, 10807.0, 10832.891671083345, 10848.077913377294, 10896.964788345094, 598788.0, 608609.0, 623083.0, 634272.0, 636136.0, 99322.5033874826, 114868.0, 138460.92308615378, 140529.0, 154119.0, 846.9661213551512, 991.1624677147798, 1015.8527013582989, 1025.2771795883884, 1076.5855145768935, 18625.627487449903, 18664.81335186685, 18840.43478695641, 18857.24571017144, 18874.678772485793, 45354.0, 55665.60835979133, 57443.712781435825, 57661.0, 57699.71150144222, 82855.3428931406, 83374.58312708397, 83417.66329346759, 84776.0, 85872.0, 6798.864022719419, 7887.84224315522, 7920.881186782088, 8156.510609363457, 8172.18278172178, 10969.725756856142, 12325.198862073936, 12388.62834114978, 12438.37808109609, 12727.0, 9343.953280233556, 10537.262391632512, 10617.787644246917, 10644.520996555018, 10698.73253168677, 10777.407242601732, 10801.67594972152, 10816.729581760204, 10871.945640272064, 11069.169812264274, 10302.742431439274, 10804.081653059264, 10847.294925829796, 11088.722781930517, 11289.266197697123, 7770.378369730295, 7828.627845980619, 7914.762557123296, 7935.523868567904, 7983.081945576333, 7680.846383072196, 8604.440711353742, 9355.298352623717, 10471.79056418852, 10843.891561084289], "value": 9515.134981194493, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.5156825599936, 1592.4346856865811, 1786.4908501077186, 1895.459793958722, 1960.7843137254908, 7944.761657150295, 8499.192576705203, 8507.276881465075, 8938.910610893898, 8975.865362019567, 8607.440516366447, 8624.180702833237, 8703.91296087039, 8769.91230087699, 8784.07767184446, 1960.4902725291383, 2343.777341152592, 2382.5234953009467, 2386.868722220277, 2389.6773935518654, 2332.825038122139, 2343.0627748900492, 2354.810820535627, 2360.0, 2379.095943541452, 2333.288347054148, 2373.1575290771784, 2373.8219633527556, 2376.394019525019, 2392.3899405651537, 2340.3914982104607, 2354.540864531417, 2367.2661474942824, 2369.7393286738443, 2370.727366352866, 2202.856814307081, 2229.030371788277, 2239.6528538076623, 2246.067881829039, 2249.7750224977535, 8670.263027642657, 8725.869111963326, 8778.122187781235, 8783.824323513558, 8802.471851688919, 5145.716985565792, 5306.70813105279, 5307.336582927134, 5309.389420216686, 5320.308359913225, 3145.182252614337, 3353.02762198962, 3519.419295816187, 3540.6636369544854, 3547.485614585896, 978.2369751593768, 993.602558976408, 1006.8691070160905, 1014.279861298479, 1017.1303535477208, 11294.491747871365, 11429.914158154961, 11450.770984580346, 11498.655040348804, 11510.884891151065, 4610.861674149781, 4680.9297860532115, 4746.430428348551, 4772.952270477251, 4776.331313616097, 9678.322517423712, 9719.465429401309, 9726.805463890683, 9729.367591106697, 9747.3664211826, 5189.299444575009, 5270.78916843322, 5285.207218917193, 5287.127623942083, 5291.788328466818, 4378.43080399546, 4575.130725162208, 4826.734529600835, 4846.224604063333, 4848.321235027099, 3970.781607011584, 3987.641112299907, 4066.532348779869, 4095.610916962884, 4126.236646220459, 1293.4567481657539, 1308.4886955566335, 1311.6917524381954, 1321.8479874814327, 1324.9536266230637, 797.2704974947974, 838.3712215838121, 885.464294102059, 893.1381217125505, 919.9816003679889, 5092.083424983539, 5093.821716239914, 5290.444503327172, 5474.233607294982, 5570.949977282788, 372.86576832340336, 420.3988296735697, 432.51126227363505, 433.6119173339843, 442.91806015887164, 3764.3600587900123, 3764.5294338207723, 3785.1483416231126, 3822.9044273893373, 3833.386658134685, 7612.885806712904, 8756.8686469703, 10163.847542286872, 10428.687139385831, 10546.314489558308, 10387.740306492398, 10479.528421221063, 10495.580176792995, 10521.053105220566, 10524.31591946521, 6949.2008419031445, 7671.156172820985, 8091.878621820678, 8118.391120665974, 8168.877466838002, 5636.239107720569, 5713.314402271754, 5735.7705691773535, 5777.826665200051, 5823.330317013598, 9278.257739380699, 9820.65627703027, 9894.653687120915, 9919.553620086968, 9928.255380846322, 9712.17446517054, 9986.850197247193, 9996.400215987063, 10042.9957004299, 10091.545880435546, 6606.504512161703, 7288.416926645961, 7415.777526674208, 7429.628518573942, 7456.552606843607, 8084.919150808417, 8130.349572034342, 8158.755237342889, 8192.30365418946, 8234.0, 7205.279472052753, 7285.599292038777, 7335.77992660221, 7463.440241981764, 7476.25237476248, 7186.784396468114, 7251.601161936144, 7253.1296244451005, 7274.745383911538, 7282.563046217244, 6722.260551339251, 6724.697388617622, 6754.628495432798, 6807.012983117321, 6812.863742725217, 4681.695689780154, 4737.431508219036, 4791.4729379767505, 4861.0, 4962.3052772611145, 4577.931331030104, 4779.617630589475, 4856.660033797742, 4857.732824694675, 5067.265246539305], "value": 4404.922951439874, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.0720786276053, 306.9033254524825, 310.52644716806867, 318.3839271010596, 321.84229727433564, 4769.499202583731, 5640.0, 5707.343655479621, 5723.9713801430935, 5745.649772303505, 5589.441055894408, 5598.188262701906, 5660.915086273699, 5669.886602267962, 5672.773089076442, 3551.0, 3593.2454184621197, 3683.9447408288897, 3786.56454507731, 4259.6805239607, 3845.3270677631444, 4190.539040705536, 4206.915861682764, 4220.662347012231, 4270.316749320109, 3887.8250478728382, 4205.053862880858, 4214.199302132601, 4214.346776249687, 4228.873133805991, 3878.3212937736066, 4174.283520348744, 4228.661707063458, 4239.215745087169, 4251.532331443539, 2840.750069969207, 3216.9223310191114, 3283.8850640227715, 3362.125847279725, 3703.6851867591017, 4950.628702847301, 5264.105102132647, 5381.67709937405, 5397.78408863645, 5416.729163541847, 4111.60939710727, 4667.6732628716, 4713.670043096985, 4904.253617973946, 4914.272578718809, 5419.945800541984, 5463.9180412293845, 5850.795222167247, 5867.149263356832, 5903.822885313448, 3949.545802232751, 4070.715049946505, 4086.5300490443683, 4086.836526538935, 4088.242055915955, 5521.889562208733, 5658.462446067658, 5944.821655350346, 5985.461308482258, 6047.971844786397, 3634.7278452541927, 3639.9090022749115, 3871.922561548753, 4125.628693417607, 4132.8140233689555, 3778.0, 3867.555231148454, 4210.936835947463, 4217.936730949038, 4255.574442555781, 4077.510698716173, 4221.282381995068, 4873.805047798049, 4895.31465594817, 4902.632302577321, 4655.743934083658, 5485.780568777204, 5559.6664200148125, 5946.464818166386, 6068.33248342682, 1246.5200897654288, 1372.0807059270114, 1390.727484351827, 1399.6640806206437, 1405.1498843199922, 735.9365716539656, 759.1194214710908, 792.5324058805332, 856.5631527920681, 916.9174774270348, 4383.684894531628, 4678.590525879175, 5169.327987361619, 5240.764165612557, 5493.395726470085, 5925.059835209538, 6057.182280392179, 6070.484008859296, 6072.210612620331, 6113.694315284218, 4507.504174540798, 4698.389209402824, 4815.855524334205, 4872.076753947603, 5338.572914166857, 2837.5743638454396, 3035.301880567482, 3149.4882456420587, 3171.4935405682118, 3175.3173067790535, 5819.992102724638, 6065.727042283108, 6313.305536390993, 6315.2742329419025, 6322.841928951721, 5893.970530147321, 6307.00417301778, 6315.179026726588, 6321.0, 6334.271558770801, 5572.972135139379, 6152.876942461216, 6210.782622608187, 6247.437730604267, 6501.4148726613885, 5367.0, 5629.296337957755, 5705.286839145107, 5964.793026580193, 6024.222854257917, 5269.789208431773, 5285.339332583427, 5828.795992140254, 6101.84745381369, 6102.877942441038, 5589.888202236014, 6000.789972351122, 6122.938770612415, 6161.722722477589, 6537.673116344259, 4100.466939297872, 4117.197146556513, 4139.8965025874595, 4148.23257697327, 4148.937765933574, 5176.585873130216, 5615.8876822464135, 5901.852453688521, 6082.330943596113, 6103.511719062553, 4995.550400463976, 5173.379194496684, 5232.659877107976, 5622.522085622604, 5873.970630146822, 4705.388299521041, 4713.700409713146, 5842.591018628656, 5903.0, 5931.288245410578, 5018.573421259234, 5031.270465782514, 5095.515925987025, 5476.835694929158, 5768.884622307446, 5046.823361182341, 5903.675297858659, 5947.613405128653, 5974.820755377345, 5993.880122397441, 5156.871078223075, 5822.592418530662, 5825.94174058254, 5848.385919478564, 6044.760824031151], "value": 4220.397840139899, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.3095811314348, 694.6873906741968, 721.1958666087314, 730.1019745712773, 753.5441058159813, 15088.77366839497, 15367.0011449256, 15424.69150616987, 15557.377704891791, 15583.22083895805, 14385.78421323681, 14423.711525769479, 14425.206613636272, 14445.927770361133, 14469.710605787877, 885.7918389178554, 906.5920335848862, 908.2824568590805, 918.0589895357245, 920.0707285641516, 876.369014309696, 897.4660077254024, 901.0673952459206, 909.0409617853162, 911.3165126155384, 961.7884065505577, 961.8412961861319, 962.4129281138535, 965.6764983730459, 967.2648786921955, 933.3233405780823, 953.9713808585753, 957.7509847439646, 959.5394210778866, 961.0149596663406, 698.0332239847796, 715.6493318274008, 718.195620904585, 718.4288490649946, 719.151401346411, 15067.698646027018, 15130.243487825564, 15248.466303679428, 15257.160856152906, 15273.236338183046, 12194.878051219463, 12265.632031039084, 12341.876581234163, 12552.309622970733, 12942.0, 9684.682450728511, 9696.612135514571, 9965.302428829984, 10376.740581485523, 10417.687469375931, 3408.931821363559, 3422.1476310118683, 3427.73539554814, 3474.1662001119803, 3540.8760693375607, 43322.700318990166, 43667.78166109181, 43727.344089838676, 49468.75265623685, 77320.0, 8969.50667713269, 9125.224355929688, 9277.396969197116, 9292.349535532583, 9430.481323527272, 21284.893575532333, 21329.0, 21363.14547418117, 21364.893175534024, 21503.892480537495, 6902.413294869892, 6905.723771049102, 6926.134233220848, 6928.203256625452, 7231.385332246818, 3911.963329717627, 3971.344728119828, 4035.9910022494378, 4087.693422993287, 4102.548719640837, 2721.4557088582364, 3250.967490325114, 3350.1289664687356, 3483.4600636901323, 3548.556430446194, 521.3795583255913, 562.6539678098022, 620.9223847019123, 636.1221514310222, 641.0544446940725, 484.57357525377444, 547.9835604931858, 569.888717001848, 595.3213336796056, 609.135028259872, 7040.190378106481, 7054.717811287594, 7112.359887610037, 7658.24173632028, 8073.233042860918, 517.5316338713395, 582.9941700583024, 588.2969851027981, 591.8579540910237, 602.208096353294, 3682.4660424238336, 3825.5409350877517, 3868.303705333011, 3896.4544963705102, 3904.2777086239134, 78623.60688196523, 81004.59497702474, 81187.59406202931, 87637.0, 88515.0, 76950.84573731398, 77183.0, 77729.0, 79096.29940706518, 82373.76439353415, 38706.80646596805, 48581.75709121432, 52165.7391713039, 68615.31384686189, 71315.64342178359, 6515.153030106056, 7034.437245020284, 7472.738454154079, 7963.721269755531, 8406.579671016547, 44869.55130448654, 45553.29428916892, 46059.53940460552, 46093.0, 46429.767851160526, 60961.085583715394, 61694.0, 62179.0, 62376.688116559126, 63439.682801585695, 10284.0, 10842.728931776768, 11289.943550282525, 11319.71700707456, 11515.0, 10818.837717434088, 11580.031394663096, 11839.704007399883, 12155.513779448724, 12640.178388404724, 11088.223824332219, 11335.886641133484, 11720.472578734149, 11746.17776755619, 11850.76298474043, 11025.944870275598, 11308.378039207922, 11345.773084538429, 11347.886521134684, 11895.405229738652, 10630.8936910633, 10930.125589952626, 10984.340939543654, 11071.612493563009, 11128.721781955515, 7681.308682218627, 9679.467629280458, 9684.85472717924, 9712.417254964725, 9897.406155630686, 9462.959074501938, 9583.760405989906, 9617.471039092638, 9645.131938125602, 9858.260630452889], "value": 6631.02759622903, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1619.0999999999995, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 108.0, 116.0, 114.0, 134.0, 172.0, 171.0, 117.0, 115.0, 115.0], "value": 127.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 119.0, 155.0, 200.0, 131.0, 141.0, 140.0, 126.0, 169.0, 119.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [273.0, 258.0, 219.0, 212.0, 192.0, 197.0, 199.0, 199.0, 193.0, 199.0], "value": 214.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 76.0, 163.0, 70.0, 58.0, 57.0, 60.0, 127.0, 52.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 103.0, 158.0, 100.0, 100.0, 84.0, 123.0, 96.0, 106.0, 113.0], "value": 105.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 88.0, 85.0, 88.0, 86.0, 110.0, 86.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 119.0, 151.0, 95.0, 137.0, 95.0, 95.0, 95.0, 99.0, 95.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 137.0, 90.0, 91.0, 90.0, 92.0, 91.0, 90.0, 90.0, 91.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 60.0, 59.0, 60.0, 63.0, 58.0, 73.0, 61.0, 55.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 70.0, 99.0, 74.0, 84.0, 107.0, 101.0, 105.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 89.0, 87.0, 86.0, 90.0, 89.0, 88.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 160.0, 131.0, 147.0, 153.0, 146.0, 164.0, 156.0, 200.0, 141.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 175.0, 170.0, 177.0, 160.0, 181.0, 167.0, 225.0, 222.0, 151.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 66.0, 70.0, 68.0, 77.0, 87.0, 84.0, 87.0, 86.0], "value": 76.1, "unit": "score"}]}]} 03:02:39 INFO - Return code: 0 03:02:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:02:39 ERROR - # TBPL SUCCESS # 03:02:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:02:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:02:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:02:39 INFO - 2016-05-26 03:02:39.275 screenresolution[1678:10419] starting screenresolution argv=screenresolution get 03:02:39 INFO - 2016-05-26 03:02:39.278 screenresolution[1678:10419] Display 0: 1600x1200x32@60 03:02:39 INFO - 2016-05-26 03:02:39.284 screenresolution[1679:10422] starting screenresolution argv=screenresolution list 03:02:39 INFO - Available Modes on Display 0 03:02:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:02:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:02:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:02:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:02:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:02:39 INFO - Intel Iris: 03:02:39 INFO - Chipset Model: Intel Iris 03:02:39 INFO - Type: GPU 03:02:39 INFO - Bus: Built-In 03:02:39 INFO - VRAM (Dynamic, Max): 1536 MB 03:02:39 INFO - Vendor: Intel (0x8086) 03:02:39 INFO - Device ID: 0x0a2e 03:02:39 INFO - Revision ID: 0x0009 03:02:39 INFO - Displays: 03:02:39 INFO - Display: 03:02:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:02:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:02:39 INFO - Display Serial Number: 1600x1200 60 03:02:39 INFO - Main Display: Yes 03:02:39 INFO - Mirror: Off 03:02:39 INFO - Online: Yes 03:02:39 INFO - Rotation: Supported 03:02:39 INFO - Return code: 0 03:02:39 INFO - Running post-action listener: _resource_record_post_action 03:02:39 INFO - ##### 03:02:39 INFO - ##### Finished run-tests step (success) 03:02:39 INFO - ##### 03:02:39 INFO - Running post-run listener: _resource_record_post_run 03:02:39 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 37312000; Write bytes: 696361984; Read time: 391; Write time: 1946 03:02:39 INFO - TinderboxPrint: CPU usage
54.3% 03:02:39 INFO - TinderboxPrint: I/O read bytes / time
37,312,000 / 391 03:02:39 INFO - TinderboxPrint: I/O write bytes / time
696,361,984 / 1,946 03:02:39 INFO - TinderboxPrint: CPU idle
2,209.7 (45.7%) 03:02:39 INFO - TinderboxPrint: CPU system
970.0 (20.1%) 03:02:39 INFO - TinderboxPrint: CPU user
1,654.0 (34.2%) 03:02:39 INFO - TinderboxPrint: Swap in / out
434,823,168 / 0 03:02:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376568320; Write bytes: 370353664; Read time: 28118; Write time: 658 03:02:39 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 36357632; Write bytes: 296861184; Read time: 375; Write time: 1234 03:02:39 INFO - Running post-run listener: _upload_blobber_files 03:02:39 INFO - Blob upload gear active. 03:02:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:02:39 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 03:02:39 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'] 03:02:39 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 03:02:40 INFO - (blobuploader) - INFO - Open directory for files ... 03:02:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:02:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:02:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:41 INFO - (blobuploader) - INFO - Done attempting. 03:02:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:02:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:02:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:42 INFO - (blobuploader) - INFO - Done attempting. 03:02:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:02:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:02:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:02:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:02:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:02:44 INFO - (blobuploader) - INFO - Done attempting. 03:02:44 INFO - (blobuploader) - INFO - Iteration through files over. 03:02:44 INFO - Return code: 0 03:02:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:02:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:02:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f122a521852cd981982b982644aa2771c9abc02494fd309a5770728918863ec411f59ccc9ed50fcaa081563b9ec57f3ef42f15dd0b75612cd003682a047df7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19c53929b4cb8743c9017e0b9fca8947517c207473cc3cc57bdc52aec67f253d42c066ab1756406d8ae70e186b0db7c84f9b51611243042d52835676f1ee148d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83fc10b04193cc64c34c05c3d9be9facb2c40eb21c637434f2e6955e61b4f5fd878f1d6fcd3196efb920638b27fbe00edad6504cdc298bbd87ae3267c8f53985"} 03:02:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:02:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:02:44 INFO - Contents: 03:02:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f122a521852cd981982b982644aa2771c9abc02494fd309a5770728918863ec411f59ccc9ed50fcaa081563b9ec57f3ef42f15dd0b75612cd003682a047df7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19c53929b4cb8743c9017e0b9fca8947517c207473cc3cc57bdc52aec67f253d42c066ab1756406d8ae70e186b0db7c84f9b51611243042d52835676f1ee148d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83fc10b04193cc64c34c05c3d9be9facb2c40eb21c637434f2e6955e61b4f5fd878f1d6fcd3196efb920638b27fbe00edad6504cdc298bbd87ae3267c8f53985"} 03:02:44 INFO - Running post-run listener: copy_logs_to_upload_dir 03:02:44 INFO - Copying logs to upload dir... 03:02:44 INFO - mkdir: /builds/slave/test/build/upload/logs 03:02:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.363233 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 03:02:44.921238) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 03:02:44.924931) ========= 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.XDT9HbACkf/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.YOWyi4dRZU/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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/6f122a521852cd981982b982644aa2771c9abc02494fd309a5770728918863ec411f59ccc9ed50fcaa081563b9ec57f3ef42f15dd0b75612cd003682a047df7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19c53929b4cb8743c9017e0b9fca8947517c207473cc3cc57bdc52aec67f253d42c066ab1756406d8ae70e186b0db7c84f9b51611243042d52835676f1ee148d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83fc10b04193cc64c34c05c3d9be9facb2c40eb21c637434f2e6955e61b4f5fd878f1d6fcd3196efb920638b27fbe00edad6504cdc298bbd87ae3267c8f53985"} build_url:https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011146 build_url: 'https://queue.taskcluster.net/v1/task/SqmkjFZ7QpaiwlnHx8mc8w/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/6f122a521852cd981982b982644aa2771c9abc02494fd309a5770728918863ec411f59ccc9ed50fcaa081563b9ec57f3ef42f15dd0b75612cd003682a047df7e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/19c53929b4cb8743c9017e0b9fca8947517c207473cc3cc57bdc52aec67f253d42c066ab1756406d8ae70e186b0db7c84f9b51611243042d52835676f1ee148d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83fc10b04193cc64c34c05c3d9be9facb2c40eb21c637434f2e6955e61b4f5fd878f1d6fcd3196efb920638b27fbe00edad6504cdc298bbd87ae3267c8f53985"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 03:02:44.955302) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 03:02:44.955622) ========= 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.XDT9HbACkf/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.YOWyi4dRZU/Listeners TMPDIR=/var/folders/dh/20gh1mt515501fbg9wc6h1q800000w/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.004583 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 03:02:45.003413) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 03:02:45.003743) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 03:02:45.004088) ========= ========= Total master_lag: 0.16 =========