builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0280 starttime: 1464736568.72 results: success (0) buildid: 20160531141745 builduid: b1ae79fd489845b78b4da3959e773d45 revision: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.721077) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.721529) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.721817) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.744254) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.744645) ========= 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.SYdMSmNf14/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.72KS6XsmtJ/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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-31 16:16:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 135M=0s 2016-05-31 16:16:08 (135 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134714 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.899643) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.899976) ========= 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.SYdMSmNf14/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.72KS6XsmtJ/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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.025673 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:08.948731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 16:16:08.949090) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 --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 d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SYdMSmNf14/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.72KS6XsmtJ/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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-31 16:16:09,029 truncating revision to first 12 chars 2016-05-31 16:16:09,029 Setting DEBUG logging. 2016-05-31 16:16:09,029 attempt 1/10 2016-05-31 16:16:09,029 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d39456c7ccf1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 16:16:09,769 unpacking tar archive at: fx-team-d39456c7ccf1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.998126 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 16:16:09.967057) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:09.967384) ========= script_repo_revision: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:09.967781) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:09.968046) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 16:16:09.983813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-31 16:16:09.984092) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SYdMSmNf14/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.72KS6XsmtJ/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:10 INFO - MultiFileLogger online at 20160531 16:16:10 in /builds/slave/test 16:16:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:16:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:10 INFO - {'append_to_log': False, 16:16:10 INFO - 'base_work_dir': '/builds/slave/test', 16:16:10 INFO - 'blob_upload_branch': 'Fx-Team', 16:16:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:10 INFO - 'branch': 'Fx-Team', 16:16:10 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:10 INFO - 'config_files': ('talos/mac_config.py',), 16:16:10 INFO - 'default_actions': ('clobber', 16:16:10 INFO - 'read-buildbot-config', 16:16:10 INFO - 'download-and-extract', 16:16:10 INFO - 'populate-webroot', 16:16:10 INFO - 'create-virtualenv', 16:16:10 INFO - 'install', 16:16:10 INFO - 'run-tests'), 16:16:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:10 INFO - 'download_minidump_stackwalk': True, 16:16:10 INFO - 'download_symbols': 'ondemand', 16:16:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:10 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:10 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:10 INFO - 'installer_path': 'installer.exe', 16:16:10 INFO - 'log_level': 'info', 16:16:10 INFO - 'log_name': 'talos', 16:16:10 INFO - 'log_to_console': True, 16:16:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:16:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:16:10 INFO - 'opt_config_files': (), 16:16:10 INFO - 'pip_index': False, 16:16:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:16:10 INFO - 'cmd': ('bash', 16:16:10 INFO - '-c', 16:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:16:10 INFO - 'enabled': True, 16:16:10 INFO - 'halt_on_failure': False, 16:16:10 INFO - 'name': 'check_screen_resolution'},), 16:16:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:16:10 INFO - 'cmd': ('bash', 16:16:10 INFO - '-c', 16:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:16:10 INFO - 'enabled': True, 16:16:10 INFO - 'halt_on_failure': False, 16:16:10 INFO - 'name': 'check_screen_resolution'},), 16:16:10 INFO - 'run_cmd_checks_enabled': True, 16:16:10 INFO - 'sps_profile': False, 16:16:10 INFO - 'sps_profile_interval': 0, 16:16:10 INFO - 'suite': 'dromaeojs', 16:16:10 INFO - 'system_bits': '32', 16:16:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:10 INFO - 'title': 't-yosemite-r7-0280', 16:16:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:10 INFO - 'use_talos_json': True, 16:16:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:10 INFO - 'work_dir': 'build'} 16:16:10 INFO - ##### 16:16:10 INFO - ##### Running clobber step. 16:16:10 INFO - ##### 16:16:10 INFO - Running pre-action listener: _resource_record_pre_action 16:16:10 INFO - Running main action method: clobber 16:16:10 INFO - rmtree: /builds/slave/test/build 16:16:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:12 INFO - Running post-action listener: _resource_record_post_action 16:16:12 INFO - ##### 16:16:12 INFO - ##### Finished clobber step (success) 16:16:12 INFO - ##### 16:16:12 INFO - ##### 16:16:12 INFO - ##### Running read-buildbot-config step. 16:16:12 INFO - ##### 16:16:12 INFO - Running pre-action listener: _resource_record_pre_action 16:16:12 INFO - Running main action method: read_buildbot_config 16:16:12 INFO - Using buildbot properties: 16:16:12 INFO - { 16:16:12 INFO - "project": "", 16:16:12 INFO - "product": "firefox", 16:16:12 INFO - "script_repo_revision": "production", 16:16:12 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:16:12 INFO - "repository": "", 16:16:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:16:12 INFO - "buildid": "20160531141745", 16:16:12 INFO - "pgo_build": "False", 16:16:12 INFO - "basedir": "/builds/slave/test", 16:16:12 INFO - "buildnumber": 734, 16:16:12 INFO - "slavename": "t-yosemite-r7-0280", 16:16:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:16:12 INFO - "platform": "yosemite_r7", 16:16:12 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:16:12 INFO - "branch": "fx-team", 16:16:12 INFO - "repo_path": "integration/fx-team", 16:16:12 INFO - "revision": "d39456c7ccf19d98bb20c3de1dd88cb3c9513c55", 16:16:12 INFO - "stage_platform": "macosx64", 16:16:12 INFO - "builduid": "b1ae79fd489845b78b4da3959e773d45", 16:16:12 INFO - "slavebuilddir": "test" 16:16:12 INFO - } 16:16:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:16:12 INFO - Running post-action listener: _resource_record_post_action 16:16:12 INFO - ##### 16:16:12 INFO - ##### Finished read-buildbot-config step (success) 16:16:12 INFO - ##### 16:16:12 INFO - ##### 16:16:12 INFO - ##### Running download-and-extract step. 16:16:12 INFO - ##### 16:16:12 INFO - Running pre-action listener: _resource_record_pre_action 16:16:12 INFO - Running main action method: download_and_extract 16:16:12 INFO - mkdir: /builds/slave/test/build/tests 16:16: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')]} 16:16:12 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:16:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:16:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:16:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:13 INFO - Downloaded 1199 bytes. 16:16:13 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:16:13 INFO - Using the following test package requirements: 16:16:13 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:16:13 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:16:13 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:16:13 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'jsshell-mac.zip'], 16:16:13 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:16:13 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:16:13 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:16:13 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:16:13 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:16:13 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:16:13 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:16:13 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:13 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:16:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:14 INFO - Downloaded 18687184 bytes. 16:16:14 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'] 16:16:14 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 16:16:17 INFO - Return code: 0 16:16:17 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 16:16: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')]} 16:16:17 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:16:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:16:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:19 INFO - Downloaded 18687184 bytes. 16:16:19 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'] 16:16:19 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 16:16:22 INFO - Return code: 0 16:16: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')]} 16:16:22 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:16:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:16:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:16:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:24 INFO - Downloaded 10834055 bytes. 16:16:24 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'] 16:16:24 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 16:16:24 INFO - Return code: 0 16:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:24 INFO - https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:16:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bcWhBtJ_SraLIhmbukj5ug/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 16:16:27 INFO - Downloaded 110990524 bytes. 16:16:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:27 INFO - mkdir: /builds/slave/test/properties 16:16:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:27 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:27 INFO - Contents: 16:16:27 INFO - build_url:https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:16:28 INFO - Running post-action listener: _resource_record_post_action 16:16:28 INFO - Running post-action listener: set_extra_try_arguments 16:16:28 INFO - ##### 16:16:28 INFO - ##### Finished download-and-extract step (success) 16:16:28 INFO - ##### 16:16:28 INFO - ##### 16:16:28 INFO - ##### Running populate-webroot step. 16:16:28 INFO - ##### 16:16:28 INFO - Running pre-action listener: _resource_record_pre_action 16:16:28 INFO - Running main action method: populate_webroot 16:16:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:28 INFO - '--tppagecycles', 16:16:28 INFO - '7'], 16:16:28 INFO - 'tests': ['tsvgm']}}, 16:16:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['damp', 'tps']}, 16:16:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'tests': ['damp', 'tps']}, 16:16:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['dromaeo_dom']}, 16:16:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['a11yr', 16:16:28 INFO - 'ts_paint', 16:16:28 INFO - 'tpaint', 16:16:28 INFO - 'sessionrestore', 16:16:28 INFO - 'sessionrestore_no_auto_restore', 16:16:28 INFO - 'tabpaint']}, 16:16:28 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:28 INFO - 'ts_paint', 16:16:28 INFO - 'tpaint', 16:16:28 INFO - 'sessionrestore', 16:16:28 INFO - 'sessionrestore_no_auto_restore', 16:16:28 INFO - 'tabpaint']}, 16:16:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:28 INFO - 'ts_paint', 16:16:28 INFO - 'tpaint', 16:16:28 INFO - 'sessionrestore', 16:16:28 INFO - 'sessionrestore_no_auto_restore', 16:16:28 INFO - 'tabpaint']}, 16:16:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:28 INFO - 'ts_paint', 16:16:28 INFO - 'tpaint', 16:16:28 INFO - 'sessionrestore', 16:16:28 INFO - 'sessionrestore_no_auto_restore', 16:16:28 INFO - 'tabpaint']}, 16:16:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['a11yr', 16:16:28 INFO - 'ts_paint', 16:16:28 INFO - 'tpaint', 16:16:28 INFO - 'sessionrestore', 16:16:28 INFO - 'sessionrestore_no_auto_restore', 16:16:28 INFO - 'tabpaint']}, 16:16:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['a11yr', 16:16:28 INFO - 'ts_paint', 16:16:28 INFO - 'tpaint', 16:16:28 INFO - 'sessionrestore', 16:16:28 INFO - 'sessionrestore_no_auto_restore', 16:16:28 INFO - 'tabpaint']}, 16:16:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['tsvgx', 16:16:28 INFO - 'tsvgr_opacity', 16:16:28 INFO - 'tart', 16:16:28 INFO - 'tscrollx', 16:16:28 INFO - 'cart']}, 16:16:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:28 INFO - 'tsvgr_opacity', 16:16:28 INFO - 'tart', 16:16:28 INFO - 'tscrollx', 16:16:28 INFO - 'cart']}, 16:16:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'talos_options': ['--disable-e10s'], 16:16:28 INFO - 'tests': ['tp5o']}, 16:16:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'tests': ['tp5o']}, 16:16:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'talos_options': ['--disable-e10s', 16:16:28 INFO - '--xperf_path', 16:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:28 INFO - 'tests': ['tp5n']}, 16:16:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:28 INFO - 'talos_options': ['--xperf_path', 16:16:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:28 INFO - 'tests': ['tp5n']}}, 16:16:28 INFO - 'talos.zip': {'path': '', 16:16:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:28 INFO - Running post-action listener: _resource_record_post_action 16:16:28 INFO - ##### 16:16:28 INFO - ##### Finished populate-webroot step (success) 16:16:28 INFO - ##### 16:16:28 INFO - ##### 16:16:28 INFO - ##### Running create-virtualenv step. 16:16:28 INFO - ##### 16:16:28 INFO - Running pre-action listener: _resource_record_pre_action 16:16:28 INFO - Running main action method: create_virtualenv 16:16:28 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:28 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 16:16:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:28 INFO - Using real prefix '/tools/python27' 16:16:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:29 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:32 INFO - Installing pip.................done. 16:16:32 INFO - Return code: 0 16:16:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:32 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:32 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 16:16:32 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 16:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:32 INFO - 'HOME': '/Users/cltbld', 16:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:32 INFO - 'LOGNAME': 'cltbld', 16:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:32 INFO - 'MOZ_NO_REMOTE': '1', 16:16:32 INFO - 'NO_EM_RESTART': '1', 16:16:32 INFO - 'PAGER': '/bin/cat', 16:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:32 INFO - 'PWD': '/builds/slave/test', 16:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:32 INFO - 'SHELL': '/bin/bash', 16:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:32 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:32 INFO - 'USER': 'cltbld', 16:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:32 INFO - 'XPC_FLAGS': '0x0', 16:16:32 INFO - 'XPC_SERVICE_NAME': '0', 16:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:32 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 16:16:32 INFO - Cleaning up... 16:16:32 INFO - Return code: 0 16:16:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:32 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:32 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 16:16:32 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 16:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:32 INFO - 'HOME': '/Users/cltbld', 16:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:32 INFO - 'LOGNAME': 'cltbld', 16:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:32 INFO - 'MOZ_NO_REMOTE': '1', 16:16:32 INFO - 'NO_EM_RESTART': '1', 16:16:32 INFO - 'PAGER': '/bin/cat', 16:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:32 INFO - 'PWD': '/builds/slave/test', 16:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:32 INFO - 'SHELL': '/bin/bash', 16:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:32 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:32 INFO - 'USER': 'cltbld', 16:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:32 INFO - 'XPC_FLAGS': '0x0', 16:16:32 INFO - 'XPC_SERVICE_NAME': '0', 16:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:32 INFO - Downloading/unpacking psutil>=3.1.1 16:16:32 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 16:16:32 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 16:16:32 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 16:16:32 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 16:16:32 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 16:16:32 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 16:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:35 INFO - Installing collected packages: psutil 16:16:35 INFO - Running setup.py install for psutil 16:16:36 INFO - building 'psutil._psutil_osx' extension 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 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 16:16:36 INFO - building 'psutil._psutil_posix' extension 16:16:36 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 16:16:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:16:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:16:36 INFO - ^ 16:16:36 INFO - 1 warning generated. 16:16:36 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 16:16:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:37 INFO - Successfully installed psutil 16:16:37 INFO - Cleaning up... 16:16:37 INFO - Return code: 0 16:16:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:37 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:37 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 16:16:37 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 16:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:37 INFO - 'HOME': '/Users/cltbld', 16:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:37 INFO - 'LOGNAME': 'cltbld', 16:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:37 INFO - 'MOZ_NO_REMOTE': '1', 16:16:37 INFO - 'NO_EM_RESTART': '1', 16:16:37 INFO - 'PAGER': '/bin/cat', 16:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:37 INFO - 'PWD': '/builds/slave/test', 16:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:37 INFO - 'SHELL': '/bin/bash', 16:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:37 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:37 INFO - 'USER': 'cltbld', 16:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:37 INFO - 'XPC_FLAGS': '0x0', 16:16:37 INFO - 'XPC_SERVICE_NAME': '0', 16:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:16:37 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 16:16:37 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 16:16:37 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 16:16:37 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 16:16:37 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 16:16:37 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 16:16:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:16:39 INFO - Installing collected packages: mozsystemmonitor 16:16:39 INFO - Running setup.py install for mozsystemmonitor 16:16:40 INFO - Successfully installed mozsystemmonitor 16:16:40 INFO - Cleaning up... 16:16:40 INFO - Return code: 0 16:16:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:40 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:40 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 16:16:40 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 16:16:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:40 INFO - 'HOME': '/Users/cltbld', 16:16:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:40 INFO - 'LOGNAME': 'cltbld', 16:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:40 INFO - 'MOZ_NO_REMOTE': '1', 16:16:40 INFO - 'NO_EM_RESTART': '1', 16:16:40 INFO - 'PAGER': '/bin/cat', 16:16:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:40 INFO - 'PWD': '/builds/slave/test', 16:16:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:40 INFO - 'SHELL': '/bin/bash', 16:16:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:40 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:40 INFO - 'USER': 'cltbld', 16:16:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:40 INFO - 'XPC_FLAGS': '0x0', 16:16:40 INFO - 'XPC_SERVICE_NAME': '0', 16:16:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:40 INFO - Downloading/unpacking blobuploader==1.2.4 16:16:40 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 16:16:40 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 16:16:40 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 16:16:40 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 16:16:40 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 16:16:40 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 16:16:42 INFO - Downloading blobuploader-1.2.4.tar.gz 16:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:16:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:16:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:16:43 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 16:16:43 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 16:16:43 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 16:16:43 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 16:16:43 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 16:16:43 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 16:16:43 INFO - Downloading docopt-0.6.1.tar.gz 16:16:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:16:44 INFO - Installing collected packages: blobuploader, requests, docopt 16:16:44 INFO - Running setup.py install for blobuploader 16:16:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:16:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:16:44 INFO - Running setup.py install for requests 16:16:44 INFO - Running setup.py install for docopt 16:16:44 INFO - Successfully installed blobuploader requests docopt 16:16:44 INFO - Cleaning up... 16:16:44 INFO - Return code: 0 16:16:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:44 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 16:16:44 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 16:16:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:44 INFO - 'HOME': '/Users/cltbld', 16:16:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:44 INFO - 'LOGNAME': 'cltbld', 16:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:44 INFO - 'MOZ_NO_REMOTE': '1', 16:16:44 INFO - 'NO_EM_RESTART': '1', 16:16:44 INFO - 'PAGER': '/bin/cat', 16:16:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:44 INFO - 'PWD': '/builds/slave/test', 16:16:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:44 INFO - 'SHELL': '/bin/bash', 16:16:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:44 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:44 INFO - 'USER': 'cltbld', 16:16:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:44 INFO - 'XPC_FLAGS': '0x0', 16:16:44 INFO - 'XPC_SERVICE_NAME': '0', 16:16:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-FhOUr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-O62n6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-VvJ3Hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-NOVfk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-OblFaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-FbTh9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-slJNBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:45 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-RXAaat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-B6G3dV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-qHVaxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-Kj40S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-xSiSS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-Zb9Pqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-WGymo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-qx8FHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-mLrFkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:46 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-x8vN1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:16:46 INFO - Running setup.py install for manifestparser 16:16:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:16:47 INFO - Running setup.py install for mozcrash 16:16:47 INFO - Running setup.py install for mozdebug 16:16:47 INFO - Running setup.py install for mozdevice 16:16:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:16:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:16:47 INFO - Running setup.py install for mozfile 16:16:47 INFO - Running setup.py install for mozhttpd 16:16:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:16:47 INFO - Running setup.py install for mozinfo 16:16:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:16:47 INFO - Running setup.py install for mozInstall 16:16:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Running setup.py install for mozleak 16:16:48 INFO - Running setup.py install for mozlog 16:16:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Running setup.py install for moznetwork 16:16:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Running setup.py install for mozprocess 16:16:48 INFO - Running setup.py install for mozprofile 16:16:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:16:48 INFO - Running setup.py install for mozrunner 16:16:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:16:49 INFO - Running setup.py install for mozscreenshot 16:16:49 INFO - Running setup.py install for moztest 16:16:49 INFO - Running setup.py install for mozversion 16:16:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:16:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:16:49 INFO - Cleaning up... 16:16:49 INFO - Return code: 0 16:16:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:49 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:49 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 16:16:49 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 16:16:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:49 INFO - 'HOME': '/Users/cltbld', 16:16:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:49 INFO - 'LOGNAME': 'cltbld', 16:16:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:49 INFO - 'MOZ_NO_REMOTE': '1', 16:16:49 INFO - 'NO_EM_RESTART': '1', 16:16:49 INFO - 'PAGER': '/bin/cat', 16:16:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:49 INFO - 'PWD': '/builds/slave/test', 16:16:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:49 INFO - 'SHELL': '/bin/bash', 16:16:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:49 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:49 INFO - 'USER': 'cltbld', 16:16:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:49 INFO - 'XPC_FLAGS': '0x0', 16:16:49 INFO - 'XPC_SERVICE_NAME': '0', 16:16:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:49 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-qPkHgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:49 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)) 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:49 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-Spuj3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-xcbjwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-NUTplW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-isiW4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-bw0Z_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-lWME0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-tYk3gu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-sRqp4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-e36UQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-nHChZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:50 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)) 16:16:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:50 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-90rGJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:51 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)) 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:51 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-E1MeLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:51 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)) 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:51 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-U98foi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:51 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)) 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:51 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-2LC2j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:51 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)) 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:51 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-g9rQDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:51 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)) 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:51 INFO - Running setup.py (path:/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/pip-QpTxCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:51 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)) 16:16:51 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)) 16:16:51 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)) 16:16:51 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)) 16:16:51 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)) 16:16:51 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)) 16:16:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:16:51 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 16:16:51 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 16:16:51 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 16:16:51 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 16:16:51 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 16:16:51 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 16:16:54 INFO - Downloading blessings-1.6.tar.gz 16:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:16:54 INFO - Installing collected packages: blessings 16:16:54 INFO - Running setup.py install for blessings 16:16:54 INFO - Successfully installed blessings 16:16:54 INFO - Cleaning up... 16:16:54 INFO - Return code: 0 16:16:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:16:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:54 INFO - Reading from file tmpfile_stdout 16:16:54 INFO - Current package versions: 16:16:54 INFO - blessings == 1.6 16:16:54 INFO - blobuploader == 1.2.4 16:16:54 INFO - docopt == 0.6.1 16:16:54 INFO - manifestparser == 1.1 16:16:54 INFO - mozInstall == 1.12 16:16:54 INFO - mozcrash == 0.17 16:16:54 INFO - mozdebug == 0.1 16:16:54 INFO - mozdevice == 0.48 16:16:54 INFO - mozfile == 1.2 16:16:54 INFO - mozhttpd == 0.7 16:16:54 INFO - mozinfo == 0.9 16:16:54 INFO - mozleak == 0.1 16:16:54 INFO - mozlog == 3.2 16:16:54 INFO - moznetwork == 0.27 16:16:54 INFO - mozprocess == 0.22 16:16:54 INFO - mozprofile == 0.28 16:16:54 INFO - mozrunner == 6.11 16:16:54 INFO - mozscreenshot == 0.1 16:16:54 INFO - mozsystemmonitor == 0.3 16:16:54 INFO - moztest == 0.7 16:16:54 INFO - mozversion == 1.4 16:16:54 INFO - psutil == 3.1.1 16:16:54 INFO - requests == 1.2.3 16:16:54 INFO - wsgiref == 0.1.2 16:16:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:54 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:54 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 16:16:54 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 16:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:54 INFO - 'HOME': '/Users/cltbld', 16:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:54 INFO - 'LOGNAME': 'cltbld', 16:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:54 INFO - 'MOZ_NO_REMOTE': '1', 16:16:54 INFO - 'NO_EM_RESTART': '1', 16:16:54 INFO - 'PAGER': '/bin/cat', 16:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:54 INFO - 'PWD': '/builds/slave/test', 16:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:54 INFO - 'SHELL': '/bin/bash', 16:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:54 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:54 INFO - 'USER': 'cltbld', 16:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:54 INFO - 'XPC_FLAGS': '0x0', 16:16:54 INFO - 'XPC_SERVICE_NAME': '0', 16:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 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)) 16:16:54 INFO - Cleaning up... 16:16:54 INFO - Return code: 0 16:16:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:54 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 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:54 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 16:16:54 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 16:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:54 INFO - 'HOME': '/Users/cltbld', 16:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:54 INFO - 'LOGNAME': 'cltbld', 16:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:54 INFO - 'MOZ_NO_REMOTE': '1', 16:16:54 INFO - 'NO_EM_RESTART': '1', 16:16:54 INFO - 'PAGER': '/bin/cat', 16:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:54 INFO - 'PWD': '/builds/slave/test', 16:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:54 INFO - 'SHELL': '/bin/bash', 16:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:54 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:54 INFO - 'USER': 'cltbld', 16:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:54 INFO - 'XPC_FLAGS': '0x0', 16:16:54 INFO - 'XPC_SERVICE_NAME': '0', 16:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:55 INFO - Downloading/unpacking jsonschema 16:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:16:57 INFO - Downloading/unpacking functools32 (from jsonschema) 16:16:57 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 16:16:57 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 16:16:57 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 16:16:57 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 16:16:57 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 16:16:57 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 16:16:57 INFO - Downloading functools32-3.2.3-2.tar.gz 16:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:16:58 INFO - warning: no files found matching '*.txt' 16:16:58 INFO - no previously-included directories found matching 'build' 16:16:58 INFO - no previously-included directories found matching 'dist' 16:16:58 INFO - no previously-included directories found matching '.git*' 16:16:58 INFO - Installing collected packages: jsonschema, functools32 16:16:58 INFO - Running setup.py install for functools32 16:16:58 INFO - warning: no files found matching '*.txt' 16:16:58 INFO - no previously-included directories found matching 'build' 16:16:58 INFO - no previously-included directories found matching 'dist' 16:16:58 INFO - no previously-included directories found matching '.git*' 16:16:58 INFO - Successfully installed jsonschema functools32 16:16:58 INFO - Cleaning up... 16:16:58 INFO - Return code: 0 16:16:58 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106e37a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc895c2420>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, '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.SYdMSmNf14/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.72KS6XsmtJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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 16:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:58 INFO - 'HOME': '/Users/cltbld', 16:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:58 INFO - 'LOGNAME': 'cltbld', 16:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:58 INFO - 'MOZ_NO_REMOTE': '1', 16:16:58 INFO - 'NO_EM_RESTART': '1', 16:16:58 INFO - 'PAGER': '/bin/cat', 16:16:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:58 INFO - 'PWD': '/builds/slave/test', 16:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:58 INFO - 'SHELL': '/bin/bash', 16:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:16:58 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:58 INFO - 'USER': 'cltbld', 16:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:58 INFO - 'XPC_FLAGS': '0x0', 16:16:58 INFO - 'XPC_SERVICE_NAME': '0', 16:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:58 INFO - Downloading/unpacking flake8 16:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:17:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:17:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:17:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:17:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:17:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:17:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:17:02 INFO - no previously-included directories found matching 'docs/_build' 16:17:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:17:02 INFO - Running setup.py install for pep8 16:17:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:17:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:17:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:17:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:17:02 INFO - no previously-included directories found matching 'docs/_build' 16:17:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:17:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:17:02 INFO - Cleaning up... 16:17:02 INFO - Return code: 0 16:17:02 INFO - Running post-action listener: _resource_record_post_action 16:17:02 INFO - Running post-action listener: _start_resource_monitoring 16:17:02 INFO - Starting resource monitoring. 16:17:02 INFO - ##### 16:17:02 INFO - ##### Finished create-virtualenv step (success) 16:17:02 INFO - ##### 16:17:02 INFO - ##### 16:17:02 INFO - ##### Running install step. 16:17:02 INFO - ##### 16:17:02 INFO - Running pre-action listener: _resource_record_pre_action 16:17:02 INFO - Running main action method: install 16:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:02 INFO - Reading from file tmpfile_stdout 16:17:02 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:03 INFO - Reading from file tmpfile_stdout 16:17:03 INFO - Output received: 16:17:03 INFO - Usage: mozinstall [options] installer 16:17:03 INFO - Options: 16:17:03 INFO - -h, --help show this help message and exit 16:17:03 INFO - -d DEST, --destination=DEST 16:17:03 INFO - Directory to install application into. [default: 16:17:03 INFO - "/builds/slave/test"] 16:17:03 INFO - --app=APP Application being installed. [default: firefox] 16:17:03 INFO - mkdir: /builds/slave/test/build/application 16:17:03 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'] 16:17:03 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 16:17:36 INFO - Reading from file tmpfile_stdout 16:17:36 INFO - Output received: 16:17:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:17:36 INFO - Running post-action listener: _resource_record_post_action 16:17:36 INFO - ##### 16:17:36 INFO - ##### Finished install step (success) 16:17:36 INFO - ##### 16:17:36 INFO - ##### 16:17:36 INFO - ##### Running run-tests step. 16:17:36 INFO - ##### 16:17:36 INFO - Running pre-action listener: _resource_record_pre_action 16:17:36 INFO - Running main action method: run_tests 16:17:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:17:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:17:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:17:36 INFO - 2016-05-31 16:17:36.715 screenresolution[1639:7433] starting screenresolution argv=screenresolution get 16:17:36 INFO - 2016-05-31 16:17:36.717 screenresolution[1639:7433] Display 0: 1600x1200x32@60 16:17:36 INFO - 2016-05-31 16:17:36.723 screenresolution[1640:7435] starting screenresolution argv=screenresolution list 16:17:36 INFO - Available Modes on Display 0 16:17:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:17:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:17:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:17:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:17:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:17:36 INFO - Intel Iris: 16:17:36 INFO - Chipset Model: Intel Iris 16:17:36 INFO - Type: GPU 16:17:36 INFO - Bus: Built-In 16:17:36 INFO - VRAM (Dynamic, Max): 1536 MB 16:17:36 INFO - Vendor: Intel (0x8086) 16:17:36 INFO - Device ID: 0x0a2e 16:17:36 INFO - Revision ID: 0x0009 16:17:36 INFO - Displays: 16:17:36 INFO - Display: 16:17:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:17:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:17:36 INFO - Display Serial Number: 1600x1200 60 16:17:36 INFO - Main Display: Yes 16:17:36 INFO - Mirror: Off 16:17:36 INFO - Online: Yes 16:17:36 INFO - Rotation: Supported 16:17:36 INFO - Return code: 0 16:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:17:36 INFO - Python 2.7.3 16:17:36 INFO - Return code: 0 16:17:36 INFO - grabbing minidump binary from tooltool 16:17:36 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')]} 16:17:36 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 0x106cdbd30>, '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 0x106affdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106cd2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:17:36 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 16:17:36 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 16:17:36 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 16:17:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:17:37 INFO - Return code: 0 16:17:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:17:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:17:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:17:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:17:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:17:38 INFO - Return code: 0 16:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0280', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0280 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:17:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SYdMSmNf14/Render', 16:17:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:38 INFO - 'HOME': '/Users/cltbld', 16:17:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:38 INFO - 'LOGNAME': 'cltbld', 16:17:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:17:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:38 INFO - 'MOZ_NO_REMOTE': '1', 16:17:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:17:38 INFO - 'NO_EM_RESTART': '1', 16:17:38 INFO - 'PAGER': '/bin/cat', 16:17:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:38 INFO - 'PWD': '/builds/slave/test', 16:17:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:17:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:38 INFO - 'SHELL': '/bin/bash', 16:17:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72KS6XsmtJ/Listeners', 16:17:38 INFO - 'TMPDIR': '/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/', 16:17:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:38 INFO - 'USER': 'cltbld', 16:17:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:38 INFO - 'XPC_FLAGS': '0x0', 16:17:38 INFO - 'XPC_SERVICE_NAME': '0', 16:17:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0280', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:17:38 INFO - mozversion application_buildid: 20160531141745 16:17:38 INFO - mozversion application_changeset: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 16:17:38 INFO - mozversion application_display_name: Nightly 16:17:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:17:38 INFO - mozversion application_name: Firefox 16:17:38 INFO - mozversion application_remotingname: firefox 16:17:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:17:38 INFO - mozversion application_vendor: Mozilla 16:17:38 INFO - mozversion application_version: 49.0a1 16:17:38 INFO - mozversion platform_buildid: 20160531141745 16:17:38 INFO - mozversion platform_changeset: d39456c7ccf19d98bb20c3de1dd88cb3c9513c55 16:17:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:17:38 INFO - mozversion platform_version: 49.0a1 16:17:38 INFO - using testdate: 1464736658 16:17:38 INFO - actual date: 1464736658 16:17:38 INFO - starting webserver on 'localhost:49275' 16:17:38 INFO - SUITE-START | Running 2 tests 16:17:38 INFO - TEST-START | dromaeo_css 16:17:38 INFO - operating with platform_type : mac_ 16:17:38 INFO - Initialising browser for dromaeo_css test... 16:17:38 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmpAJOTQM/profile http://localhost:49275/getInfo.html) 16:17:40 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 16:17:40 INFO - PROCESS | 1658 | colorDepth:24 16:17:40 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 16:17:40 INFO - PROCESS | 1658 | __metrics 16:17:40 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:17:40 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:17:41 INFO - TEST-INFO | 1658: exit 0 16:17:41 INFO - Browser initialized. 16:17:41 INFO - Running cycle 1/1 for dromaeo_css test... 16:17:41 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmpAJOTQM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:53 INFO - PROCESS | 1660 | RSS: Main: 241930240 16:17:53 INFO - PROCESS | 1660 | 16:18:45 INFO - PROCESS | 1660 | 1464736725116 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] 16:20:55 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:20:56 INFO - PROCESS | 1660 | RSS: Main: 351133696 16:20:56 INFO - PROCESS | 1660 | 16:23:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:24:00 INFO - PROCESS | 1660 | RSS: Main: 375173120 16:24:00 INFO - PROCESS | 1660 | 16:27:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:27:03 INFO - PROCESS | 1660 | RSS: Main: 374169600 16:27:03 INFO - PROCESS | 1660 | 16:30:05 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:30:06 INFO - PROCESS | 1660 | RSS: Main: 366399488 16:30:06 INFO - PROCESS | 1660 | 16:33:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:33:09 INFO - PROCESS | 1660 | RSS: Main: 345812992 16:33:09 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:36:13 INFO - PROCESS | 1660 | RSS: Main: 378155008 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | __start_tp_report 16:36:13 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 16:36:13 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 16:36:13 INFO - PROCESS | 1660 | |i|pagename|runs| 16:36:13 INFO - PROCESS | 1660 | |0;dojo.html;3218.4045951498965;3424.5205671206036;3455.7580969332157;3482.094655389598;3521.4189658706314;5541.750621222044;5611.523020543254;5622.4939755422;5667.829965101048;5716.22830917826;5597.63615365002;5640.802571909973;5739.110437882125;5747.655140691561;5790.015697331453;5060.418051924021;5081.263216833557;5142.948570514284;5154.793808247684;5195.532402083812;5195.324607800981;5203.349581302337;5217.452167522412;5226.869328266805;5260.8158714445;5076.13705670037;5094.133997220481;5098.056859481007;5115.948840511584;5190.896182076356;5105.744712764347;5123.897522049576;5141.691498510064;5183.792648294064;5187.429382767892;5082.466341034212;5126.128558145087;5141.794328226866;5148.84553463393;5155.510226528511;5501.449855014466;5579.107342825169;5611.831645050655;5658.971705141489;5669.546436285129;6240.656763877985;6248.218972628421;6254.718537665816;6266.811995640138;6266.905996410058;6259.436650701458;6322.968385158091;6336.96831515844;6339.651319177444;6344.777932772331;5620.381758006616;5691.231683722686;5741.339745929272;5764.769409223668;5765.94234057654;6370.140031095835;6380.234371875405;6390.45681117101;6391.712372943229;6397.936020639827;5908.20239267702;6125.785597504066;6134.6625935573975;6137.478314343241;6142.57002009855;6138.75626760427;6201.224846894138;6223.346548612421;6253.155823963798;6253.968730156411;6124.5100391968535;6145.631262124198;6168.8766224675255;6195.1636529068;6197.628142311476;6065.5754097212775;6112.449879510866;6224.968875155686;6226.719797609118;6233.750649974041;4753.809847606126;4803;4813.663043586986;4821.493743156988;4886.682365646222;4296.61330480252;4310.956890431118;4314.288142456459;4315.352697095461;4353.455818022747;5114.3095682083185;5232.895342093137;5429.131338985743;5479.0959491684225;5484.424135465799;6106.023036314168;6168;6235.314115447297;6251.156093927352;6298.338674439209;5593.524550413261;5637.238972738628;5644.294463192101;5657.717114144276;5684.289463817023;5293.682379057192;5389.9191512127345;5393.271908292409;5399.136138217867;5401.5948803839865;6145.416185462464;6247.562670613104;6291.056341548621;6311.368863113652;6338.683065846782;6244.65654389013;6250.687465626609;6269.0909818075215;6270.7805226816845;6280.340564240689;6139.829471381233;6326.177596912372;6330.461910737639;6339.778107766206;6341.112244285711;6100.127044624312;6130.785422510191;6145.863015342176;6154.41533054377;6154.723037463415;6138.508919286535;6213.254409470893;6213.254409470893;6227.532935029982;6262.5929314594405;5956.086228861121;6145.525073982301;6164.044573091178;6168.444839964424;6182.969085154546;6134.509239260938;6141.109539116893;6151;6163.3836616338;6165.907511387422;5789.56578256624;5829.15477255804;5859.912101318569;5955.731992060281;5966.284045914518;6145.539084568586;6150.50795936333;6163.96918015407;6166.599171053867;6168.444839964245;6028.388282963624;6038.033914573647;6045.365236650088;6051.152838602688;6059.454649081604 16:36:13 INFO - PROCESS | 1660 | |1;ext.html;497.27397998921595;514.5009340939289;522.7752066611356;527.4093015822278;547.7480359034844;29809.9566515172;41312;42165.367519487234;42201.36697949533;42217.788911055475;42895.57104428955;43429.565704342946;43504.347434788506;43534;43655.56344436554;9297.67458138967;9529.237660987139;9545.284103692251;9546.28402869784;9553.140217380434;9374;9541.427514349127;9543.522823858813;9565.186959108449;9570.234381249518;9551.519514475232;9580.425174489517;9583.329166958316;9586.424814511116;9586.808263834719;9521.857172142423;9554.04459554046;9561.808763824754;9564.04359564038;9581.616735330543;9312.813743725155;9421.811563768686;9428.15146636806;9430.905690943071;9459.337846350758;40233.396499052535;40629.39055914163;40647.7967610163;40676.796616017025;40685.389719154235;2819.901303454369;3473.1490784757643;3524.242287908112;3576.517170182018;3693.132113953219;30618;31119.533207001914;31544.526832097537;31636.209094772814;32269.354612907846;3095.3197453369035;3199.600049993751;3251.561039259717;3275.3121844412703;3400.96599034009;504135;505745.99999999633;506166;507234.92765072244;508402;64833.675831621484;65405.672971634835;65444;65472.69054618881;65680.34319656838;34347.65652343495;34575;35305;36921.81539092287;68764.96852547115;16492.83507164913;16578.75131873023;16739.246733897005;16739.414120505968;16750.664986700198;12686.619401417787;12789.552365667158;12792.680182995313;12800.551980680631;12813.231206127475;19809;20010;20011;20020.69968950467;20042;7937.515303853304;8016.919830801734;8067.233612806774;8074.555899425588;8095.935536438288;838.7944953486435;945.9858102128474;1012.6810054832704;1059.279689810936;1092.5192915117323;30280.54579181314;30384.088477345715;30924.536131958037;31027.844860775553;31317;864.7751584587927;1034.6792494326783;1102.162356608983;1162.7965106106567;1189.2923710392304;15613.531594052196;15616.843831561766;15663.68672626541;15994.520164395086;16107.114108724132;650243;663150;668496;669319;669982;42383;42557.36163957543;42639.147217055484;42647.720568382996;42747.717568472995;1305.5822136916286;1340.8659134086513;1413.4063693249066;1432.5917113622568;1462.648964248594;28129.859350703115;30309.848450757607;30777.076687699406;31257.749690012144;31881.36237275288;42544;51205.231921521954;51821.7408912953;51919.702007448606;52095.47904521058;482883;497604.51197743777;497785;497854;499308;23614.409639759142;23630.29109126729;23973.640395394432;26971;27473.31316717098;40434.59565404426;48790;49458.505414945386;51230;52125.7393713029;38219.617803822715;39825.00437489086;40363.394549082375;40468.05518416095;40674.79662601668;38372.84881453561;38659.64691235793;39228;39421.60578394294;39819;32224.677753222168;32554.348913021135;32674.83662581672;33224.66775332216;38607.42088868725;32239.194020149684;34333.82833085819;34853.825730872195;34916.82541587276;34964.82517587396;25167.49665006653;25379.74620253774;25416.745832541437;25551.744482554936;25878.964841406134 16:36:13 INFO - PROCESS | 1660 | |2;jquery.html;384.1107835361139;387.7499013136527;387.7692772800183;401.02350775860765;405.7058632491444;19239.326623568177;20921.47696307593;21162.365129046153;21288.8291143987;21331.68002479964;20127.49681257969;20536.8973155134;20943.37169884906;20980.475488112806;21186.57626847466;5030.094582975063;5695.77216911323;5718.799842005531;5863.442972917587;5868.383819698913;5818.389069147742;5826.242588463515;5838;5900.262467191601;5919.348871624118;5620.409856965021;5958.225430694004;5975.462208401243;5996.070609055603;6015.639061656293;5837.766489340422;5851.590388672795;5915.940840591604;5925.170476133323;5930.5552083593475;5580.776768929238;5665.320161580596;5730.111832665943;5731.971340143273;5743.597948143632;11260.718482037933;13118.606441806762;13332.133411328232;13430.261335626537;13479.326033698373;2175.2930297653324;2179.934601961944;2228.765979572154;2242.6411774116227;2300.919467818618;9882.703518894445;9907.603695852158;9926.511023346484;10162.390256584555;10488.213383996155;1808.7106063029983;1902.7526421565246;1904.9141989066238;1915.8467322614158;1997.0813425823992;119154;125247;125735;127675;128277;10026.248031397674;11151.721206969729;11435.256708313933;11449.141314401453;11498.885011149781;13877.583672489842;14541.854581454261;14644.707105857824;14649.267536623127;14655.706885862437;8372.28835548985;8412.621432035574;8440.957795211107;8556.486610803371;8568.314534837198;7031.8945215821805;8057.59712014397;8208.302294305051;8247.876281855777;8344.41589088758;4376.846810361622;4441.933370999438;4546;4552.362669226311;4634.281686338623;1600.8559229669386;1722.7588137660737;1819.2359209132196;1853.3698542495347;1938.2053358123103;735.7866218796596;893.4549924546021;918.9632414703337;978.8238117138841;1072.9088027517748;18545.44363669092;19658;19988.50028749293;20269.087891044644;20767.065482053345;848.3552500099845;938.1978408460805;960.2893858544627;963.8409662405625;974.1865542272141;8299.502029878227;10267.229957753198;10684;10692.893071069346;10696.411697356565;564224;570705;583162;591345;592021;125273;130986.34506827405;133397;133683.99474007898;137556;877.8463768840427;1024.3546565663585;1052;1073.4900922062113;1081.1837063017372;17152.228149733044;17486.73769893427;17785.377511787025;17790.644187116442;18209.817901820814;37253.6274637261;51136.74431627818;51569.96860062703;51971.48028519817;53753.46246537485;74558;75318.62340688262;75899;78377.216227837;78476.21523784689;7380.96309518449;8124.959375203086;8189.549574773445;8244;8365.372597055124;9928.453935033642;12254.080943929419;12614.810777838524;12823.871761282267;12823.974082073599;10746.677599672023;11065.170112241452;11067.00396964277;11136.777264454828;11286.153538484483;10609.840852387375;10940.39827809478;11023.0630396414;11154.330740155616;11188.384638844942;11237.55049797999;11269.718257043312;11297;11355.829662554903;11399.658010259374;7021.4031807294905;7411.7035318588805;8067.273945344947;8194.22154895284;8315.708950186714;10544.578216871547;10577.471126443801;11223.102151827998;11274.943625281821;11366.772664546497 16:36:13 INFO - PROCESS | 1660 | |3;mootools.html;1399.916005039697;1544.714227867844;1572.7483602623581;1808.61114860305;1972.9641937982567;7946.682132714692;8702.26030787383;8919.95540022299;8922.687705930286;9050.63797448103;8639.956800216007;8878.42290251135;8898.37711360205;8906.732798016053;8907.28741700664;2081.6469294958247;2154.9784502154935;2158.665406861939;2342.472943587692;2376.786089251967;2187.660912558556;2213.6679498075337;2347.788699017088;2425.708914930202;2437.8293519453646;2396.2571602803187;2397.61638137898;2397.940051498709;2398.2325655790155;2400.4838959623676;2346.4044449774146;2393.7366889642126;2395.796357309631;2395.940101497459;2396.8681722505344;2108.9156433742633;2188.7482939462006;2189.627763280246;2193.670949357593;2329.9534009319887;8628.09405012471;8721.21509064181;8810.207081362709;8813.162749538784;8858.424202426822;5284.603654725911;5462.262594549725;5463.890722185534;5466.863328416782;5474.562035037187;3563.572371315433;3578.964210357889;3597.190632107781;3636.9454458182886;3666.7799932004164;1058.6612284069101;1073.1200415659162;1092.8524649172339;1100.4827730966513;1115.2249186815168;11462.598809041727;11704.590339338167;11733.061355091571;11734.647960561197;11736.823947640793;4773.362619808279;4784.2823576463115;4875.683080599821;4894.559489645949;4916.262560615936;10179.54192061359;10215.336003159771;10223.386596804216;10235.027672371112;10243.231757618209;5431.266778984866;5436.21174929633;5436.945630543723;5442.102053161184;5461.1808228766;4463.687541872103;4772.761361931959;4781.760911954389;4879.95120048795;4943.307936888767;3831.885043448701;3950.743201691881;3957.5053118360206;3963.3460479021214;4015.4579131817413;1160.8316794064813;1292.9418176182094;1303.648015035935;1316.8090626859052;1329.1360615599745;847.559269180023;855.7005048233193;931.6040682709821;946.990530094704;950.8352268471068;6151;6195.101710251988;6258.311585725566;6268.247810262797;6340.778072767431;943.7499062748377;993.5231089077283;1014.0569270578286;1019.5819713917258;1030.3817709374316;3467.7052450541987;4063.4514340563646;4138.606832350922;4148.2947898857265;4215.557366476537;10448.582056717798;10956.452177391098;10959.780804383867;10966.835497467544;10983.835242471368;10760.730981725363;10896.291741036806;10902.5093870776;10908.236423450282;10917.508712107974;8050.355971522381;8079.272865442138;8180.877286840702;8192.426530142951;8205.425620206648;4851.660383773102;5846.327672317738;5878.764849406147;6013.278406591237;6059.151718759465;9679.951600241953;9883.901160988298;9922.00779922002;10085.495725213563;10091.596336146471;10013.549390277309;10128.03783640553;10167.339122956984;10209.846852297072;10221.233407494616;7126.643667816692;7169.964150179216;7278.67245973943;7280.963595182202;7287.708491660274;7479.551226926402;7760.573168475788;8156.510609363457;8402.495850249004;8409.747707568538;6734.528582999143;7082.468814838805;7333.559986400833;7505.88741168872;7716.729914452967;7202.747903823341;7310.342069213796;7416.332530072319;7446.404287657083;7526.209747976383;6961.477889158232;7045.048918395951;7116.395106416013;7134.785956421316;7134.821629459305;4526.2531682273375;4659.394278743877;5040.42035165946;5060.873478163076;5061.924071139009;5005.174146265826;5058.924116138334;5106.387233532;5126.179811230185;5158.3552055993005 16:36:13 INFO - PROCESS | 1660 | |4;prototype.html;307.6308429884139;312.16340208241905;316.6263808705728;319.18925928142505;322.4212538493404;4651.813927442907;5334.653247538912;5364.865878353034;5386.175815171228;5427.267318911946;4105.486814148231;5325.81641526974;5352.384475785286;5356.464353564651;5488.780448782054;3510.2803925195403;3559.448285515736;3814.5396219438444;3968.8610898618554;4010.5788892166192;3982.4822773039464;3983.4821473208444;4040.4949381327333;4061.5784475433657;4064.9024763313887;3567.696745776599;4012.117334186474;4020.959790402088;4027.2750904837117;4035.051762835731;3732.7976493868746;3825.674817640504;3935.701218597856;3961.2473630010495;4037.0916543777707;3018.592490013842;3479.634638362986;3503.7897726136257;3509.7364948618733;3552.875649352286;4051.4327994080854;5056.393232812086;5099.184130539133;5108.2848401223855;5126.334306160813;3799.145192331731;4084.856240252734;4559.475660299042;4595.678302518825;4648.930266046012;4856.1016212000895;4912.754362281908;5526.1264142646005;5544.1960915667005;5560.277163968699;3788.90527736806;3792.9190180798614;3793.8103094845424;3801.4414090222876;3806.771593704359;4591.471980722193;5596.132599447052;5819.388964158787;5839.729751339263;5842.649441033552;3390.7965522068753;3473.4616134498688;3850.9807450962567;3853.420097759905;3862.343401621731;3261.510773383964;3607.3687104756564;3902.20004898995;3909.706771992112;3928.842846286174;4558.840440584564;4568.1548913451115;4572.199865023607;4585.495595484494;4585.564371384779;4282.700210985264;5311.946880531223;5312.893742125136;5562.721863906789;5632.8028519002455;1229.6065259117177;1296.027979015738;1326.6733266733268;1326.8076128961247;1330.9286024749977;702.9736584586459;778.0196951840721;799.2287442617902;818.4434584482609;819.4468733604793;4344.891377715582;4352.499462561783;4526.524714904954;4951.72765497901;5034.496550345009;4734.467060122818;4771.900363839899;5533.592887094127;5660.8867822643315;5754;4604.401427814362;5170.767315470813;5173.3533308336455;5182.015417070745;5193.350831146106;2955.6896525864477;2982.2991596974693;2991.3867657130236;2997.415503976747;3007.3985202959057;4839.661223714376;5243.187305967581;5586.804461843816;5672.290963629546;5941.673207973517;4226.957730422657;5025.899482010413;5338.679679219259;5495.587830912697;5749.751390464374;5178.97410512945;5644.266245388156;5860.384659610765;5862.501687356623;5882.970270310793;4964.982178653295;5584.972075139598;5634.4365563443325;5681.517071049007;5685.602007859576;5009.323741294878;5150.793968241228;5643.52030077448;5660.7169641518585;5692.829215123553;5262.394824595068;5803.100519419497;5982.850428739316;5997.130416089555;6000.669963152069;3271.7382609391666;3606.8016259106003;3778.962210377861;3780.2250538640064;3804.9619503804606;5062.949370506248;5418.45815418455;5589.552835773047;5678.574106941914;5710.5146062585145;5022.773975171199;5128.564072053768;5163.586913047023;5359.276497672764;5492.642978206404;4690.765461726832;5201.583873290203;5493.423190564933;5508.925759476863;5513.476219759116;5141.485851414828;5533.750981205936;5558.583106267127;5579.46995035471;5588.832335029955;5398.541124004503;5501.779928802963;5556.444355564411;5595.944040559542;5681.346645135863;4802.27965805132;4819.927701084416;4901.779419926041;5360.490753378423;5542.916856247079 16:36:13 INFO - PROCESS | 1660 | |5;yui.html;653.2911790707086;683.1358331710384;703.3072423662693;826.6941231744255;827.5159031966299;13932.373043213054;14232.501862434803;14827.703445931074;15063.548093557198;15370;13641.863581364209;14248;14255.572332830006;14260.643483912907;14267.857321426782;703.8416356319813;733.1275781819644;868.2402897464718;879.3712495565669;898.7124120281512;877.6577134917387;893.0801274687083;894.3292530602048;894.6645008121955;899.4198741811527;954.5513608603945;957.2724729205784;959.2326139088736;960.9903900961006;961.5048250151178;748.8127968007998;769.5536588778496;774.8450309938034;775.4068137874474;956.0391806234725;672.7409947170325;696.223710562724;697.1146643762394;697.3619138488277;697.3793323941696;12137.846904544054;14374.568762937128;14517.419303227858;14541.854581454261;14553.345099470549;13411.46354145833;13438.395272212774;13580.981426392962;13596.864031359659;13605;11269.380184089872;11361;12094.395280236042;12173.573924912675;12302.692432689164;4505.954940450619;4522.6155776759015;4578.793954272066;4625.259958406638;4665.300204969248;78846.21153788446;78874;78955;79103.85824921362;79280;8851.33614978879;8852.3803333766;10668.89331106679;10731.658542682164;10798.190135739851;20623.278185263443;20719.378418647466;20741.962901854848;20745.481362966046;20809.89595052015;7774.727884524128;7807.570583617956;7853.243633348775;7888.2506161914525;8051.275385215359;4259.467566554181;4316.719413238129;4317.762523061262;4337.436133302651;4343.218220720238;3759.6992240620684;3926.293267211929;3938.5470670873215;3939;3978.542467616261;738.092146659601;744.9813754656069;745.3478206569251;747.6971826410313;748.8689479341093;565.0535353283234;616.9537284703665;665.4377051391566;676.5839009009424;685.413971054751;9679.66121185755;9682.370645907993;9684.41893486393;9696.806063878683;9727.270454715925;664.5016237821634;684.8253695307665;687.6836655138692;688.4148473797233;702.3959394920364;3931.253061918226;4328.978355108267;4358.171947329997;4426.535213802569;4508.391367165456;94353.52823235978;95260;98883.50558247163;99024;99028.00971990332;78626.2137378619;87309.56345218234;87444;87683.56158219295;87988;74719;75143.57582247924;75931.62034189794;78567;78754.60622696964;8721.869171962553;8741.470242707628;8758.080401558;8822.955885220534;8922.776930576787;49388.753056235924;49524.75237623789;49876.75061624814;50010.249641263836;50396.244056339914;59197.40802591919;62373.68813156087;64106.67946660237;64571.67714161399;68477.31522684709;12574.30841303737;13126.934365328112;13240.337983100613;13336.461146077376;13370.264635445144;11948.34284114382;14363.21002344881;14418.639534011734;14530.273486325852;14749.631259218177;10940.343579385262;13464.596062118153;13526.864731352955;13658.385372658055;13847.169169849842;13238.205707657571;13249.337533123498;13629.591112266648;13823.723525529635;14086.22525761093;12917.741645166856;12936.935315323362;13046.478140874258;13166.210027398387;13171.2755798432;10932.180086493703;11175.10599152082;11285.887141128813;11316.773664526829;11561.479733411848;9182.908170918205;10977.670669879917;10993.835092473459;11300.604478843201;11312 16:36:13 INFO - PROCESS | 1660 | __end_tp_report 16:36:13 INFO - PROCESS | 1660 | __start_cc_report 16:36:13 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1187 16:36:13 INFO - PROCESS | 1660 | __end_cc_report 16:36:13 INFO - PROCESS | 1660 | __startTimestamp1464737773378__endTimestamp 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | ------- Summary: start ------- 16:36:13 INFO - PROCESS | 1660 | Number of tests: 6 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5717.22 Median:6028.39 stddev:658.73 (10.9%) stddev-sans-first:630.91 16:36:13 INFO - PROCESS | 1660 | Values: 3218.4 3424.5 3455.8 3482.1 3521.4 5541.8 5611.5 5622.5 5667.8 5716.2 5597.6 5640.8 5739.1 5747.7 5790.0 5060.4 5081.3 5142.9 5154.8 5195.5 5195.3 5203.3 5217.5 5226.9 5260.8 5076.1 5094.1 5098.1 5115.9 5190.9 5105.7 5123.9 5141.7 5183.8 5187.4 5082.5 5126.1 5141.8 5148.8 5155.5 5501.4 5579.1 5611.8 5659.0 5669.5 6240.7 6248.2 6254.7 6266.8 6266.9 6259.4 6323.0 6337.0 6339.7 6344.8 5620.4 5691.2 5741.3 5764.8 5765.9 6370.1 6380.2 6390.5 6391.7 6397.9 5908.2 6125.8 6134.7 6137.5 6142.6 6138.8 6201.2 6223.3 6253.2 6254.0 6124.5 6145.6 6168.9 6195.2 6197.6 6065.6 6112.4 6225.0 6226.7 6233.8 4753.8 4803.0 4813.7 4821.5 4886.7 4296.6 4311.0 4314.3 4315.4 4353.5 5114.3 5232.9 5429.1 5479.1 5484.4 6106.0 6168.0 6235.3 6251.2 6298.3 5593.5 5637.2 5644.3 5657.7 5684.3 5293.7 5389.9 5393.3 5399.1 5401.6 6145.4 6247.6 6291.1 6311.4 6338.7 6244.7 6250.7 6269.1 6270.8 6280.3 6139.8 6326.2 6330.5 6339.8 6341.1 6100.1 6130.8 6145.9 6154.4 6154.7 6138.5 6213.3 6213.3 6227.5 6262.6 5956.1 6145.5 6164.0 6168.4 6183.0 6134.5 6141.1 6151.0 6163.4 6165.9 5789.6 5829.2 5859.9 5955.7 5966.3 6145.5 6150.5 6164.0 6166.6 6168.4 6028.4 6038.0 6045.4 6051.2 6059.5 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:68314.84 Median:40399.00 stddev:149863.32 (371.0%) stddev-sans-first:150197.23 16:36:13 INFO - PROCESS | 1660 | Values: 497.3 514.5 522.8 527.4 547.7 29810.0 41312.0 42165.4 42201.4 42217.8 42895.6 43429.6 43504.3 43534.0 43655.6 9297.7 9529.2 9545.3 9546.3 9553.1 9374.0 9541.4 9543.5 9565.2 9570.2 9551.5 9580.4 9583.3 9586.4 9586.8 9521.9 9554.0 9561.8 9564.0 9581.6 9312.8 9421.8 9428.2 9430.9 9459.3 40233.4 40629.4 40647.8 40676.8 40685.4 2819.9 3473.1 3524.2 3576.5 3693.1 30618.0 31119.5 31544.5 31636.2 32269.4 3095.3 3199.6 3251.6 3275.3 3401.0 504135.0 505746.0 506166.0 507234.9 508402.0 64833.7 65405.7 65444.0 65472.7 65680.3 34347.7 34575.0 35305.0 36921.8 68765.0 16492.8 16578.8 16739.2 16739.4 16750.7 12686.6 12789.6 12792.7 12800.6 12813.2 19809.0 20010.0 20011.0 20020.7 20042.0 7937.5 8016.9 8067.2 8074.6 8095.9 838.8 946.0 1012.7 1059.3 1092.5 30280.5 30384.1 30924.5 31027.8 31317.0 864.8 1034.7 1102.2 1162.8 1189.3 15613.5 15616.8 15663.7 15994.5 16107.1 650243.0 663150.0 668496.0 669319.0 669982.0 42383.0 42557.4 42639.1 42647.7 42747.7 1305.6 1340.9 1413.4 1432.6 1462.6 28129.9 30309.8 30777.1 31257.7 31881.4 42544.0 51205.2 51821.7 51919.7 52095.5 482883.0 497604.5 497785.0 497854.0 499308.0 23614.4 23630.3 23973.6 26971.0 27473.3 40434.6 48790.0 49458.5 51230.0 52125.7 38219.6 39825.0 40363.4 40468.1 40674.8 38372.8 38659.6 39228.0 39421.6 39819.0 32224.7 32554.3 32674.8 33224.7 38607.4 32239.2 34333.8 34853.8 34916.8 34964.8 25167.5 25379.7 25416.7 25551.7 25879.0 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34238.40 Median:2177.61 stddev:97386.06 (4472.1%) stddev-sans-first:97626.08 16:36:13 INFO - PROCESS | 1660 | Values: 384.1 387.7 387.8 401.0 405.7 19239.3 20921.5 21162.4 21288.8 21331.7 20127.5 20536.9 20943.4 20980.5 21186.6 5030.1 5695.8 5718.8 5863.4 5868.4 5818.4 5826.2 5838.0 5900.3 5919.3 5620.4 5958.2 5975.5 5996.1 6015.6 5837.8 5851.6 5915.9 5925.2 5930.6 5580.8 5665.3 5730.1 5732.0 5743.6 11260.7 13118.6 13332.1 13430.3 13479.3 2175.3 2179.9 2228.8 2242.6 2300.9 9882.7 9907.6 9926.5 10162.4 10488.2 1808.7 1902.8 1904.9 1915.8 1997.1 119154.0 125247.0 125735.0 127675.0 128277.0 10026.2 11151.7 11435.3 11449.1 11498.9 13877.6 14541.9 14644.7 14649.3 14655.7 8372.3 8412.6 8441.0 8556.5 8568.3 7031.9 8057.6 8208.3 8247.9 8344.4 4376.8 4441.9 4546.0 4552.4 4634.3 1600.9 1722.8 1819.2 1853.4 1938.2 735.8 893.5 919.0 978.8 1072.9 18545.4 19658.0 19988.5 20269.1 20767.1 848.4 938.2 960.3 963.8 974.2 8299.5 10267.2 10684.0 10692.9 10696.4 564224.0 570705.0 583162.0 591345.0 592021.0 125273.0 130986.3 133397.0 133684.0 137556.0 877.8 1024.4 1052.0 1073.5 1081.2 17152.2 17486.7 17785.4 17790.6 18209.8 37253.6 51136.7 51570.0 51971.5 53753.5 74558.0 75318.6 75899.0 78377.2 78476.2 7381.0 8125.0 8189.5 8244.0 8365.4 9928.5 12254.1 12614.8 12823.9 12824.0 10746.7 11065.2 11067.0 11136.8 11286.2 10609.8 10940.4 11023.1 11154.3 11188.4 11237.6 11269.7 11297.0 11355.8 11399.7 7021.4 7411.7 8067.3 8194.2 8315.7 10544.6 10577.5 11223.1 11274.9 11366.8 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5739.71 Median:4783.02 stddev:3218.18 (67.3%) stddev-sans-first:3210.68 16:36:13 INFO - PROCESS | 1660 | Values: 1399.9 1544.7 1572.7 1808.6 1973.0 7946.7 8702.3 8920.0 8922.7 9050.6 8640.0 8878.4 8898.4 8906.7 8907.3 2081.6 2155.0 2158.7 2342.5 2376.8 2187.7 2213.7 2347.8 2425.7 2437.8 2396.3 2397.6 2397.9 2398.2 2400.5 2346.4 2393.7 2395.8 2395.9 2396.9 2108.9 2188.7 2189.6 2193.7 2330.0 8628.1 8721.2 8810.2 8813.2 8858.4 5284.6 5462.3 5463.9 5466.9 5474.6 3563.6 3579.0 3597.2 3636.9 3666.8 1058.7 1073.1 1092.9 1100.5 1115.2 11462.6 11704.6 11733.1 11734.6 11736.8 4773.4 4784.3 4875.7 4894.6 4916.3 10179.5 10215.3 10223.4 10235.0 10243.2 5431.3 5436.2 5436.9 5442.1 5461.2 4463.7 4772.8 4781.8 4880.0 4943.3 3831.9 3950.7 3957.5 3963.3 4015.5 1160.8 1292.9 1303.6 1316.8 1329.1 847.6 855.7 931.6 947.0 950.8 6151.0 6195.1 6258.3 6268.2 6340.8 943.7 993.5 1014.1 1019.6 1030.4 3467.7 4063.5 4138.6 4148.3 4215.6 10448.6 10956.5 10959.8 10966.8 10983.8 10760.7 10896.3 10902.5 10908.2 10917.5 8050.4 8079.3 8180.9 8192.4 8205.4 4851.7 5846.3 5878.8 6013.3 6059.2 9680.0 9883.9 9922.0 10085.5 10091.6 10013.5 10128.0 10167.3 10209.8 10221.2 7126.6 7170.0 7278.7 7281.0 7287.7 7479.6 7760.6 8156.5 8402.5 8409.7 6734.5 7082.5 7333.6 7505.9 7716.7 7202.7 7310.3 7416.3 7446.4 7526.2 6961.5 7045.0 7116.4 7134.8 7134.8 4526.3 4659.4 5040.4 5060.9 5061.9 5005.2 5058.9 5106.4 5126.2 5158.4 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4435.01 Median:4987.15 stddev:1366.58 (27.4%) stddev-sans-first:1334.84 16:36:13 INFO - PROCESS | 1660 | Values: 307.6 312.2 316.6 319.2 322.4 4651.8 5334.7 5364.9 5386.2 5427.3 4105.5 5325.8 5352.4 5356.5 5488.8 3510.3 3559.4 3814.5 3968.9 4010.6 3982.5 3983.5 4040.5 4061.6 4064.9 3567.7 4012.1 4021.0 4027.3 4035.1 3732.8 3825.7 3935.7 3961.2 4037.1 3018.6 3479.6 3503.8 3509.7 3552.9 4051.4 5056.4 5099.2 5108.3 5126.3 3799.1 4084.9 4559.5 4595.7 4648.9 4856.1 4912.8 5526.1 5544.2 5560.3 3788.9 3792.9 3793.8 3801.4 3806.8 4591.5 5596.1 5819.4 5839.7 5842.6 3390.8 3473.5 3851.0 3853.4 3862.3 3261.5 3607.4 3902.2 3909.7 3928.8 4558.8 4568.2 4572.2 4585.5 4585.6 4282.7 5311.9 5312.9 5562.7 5632.8 1229.6 1296.0 1326.7 1326.8 1330.9 703.0 778.0 799.2 818.4 819.4 4344.9 4352.5 4526.5 4951.7 5034.5 4734.5 4771.9 5533.6 5660.9 5754.0 4604.4 5170.8 5173.4 5182.0 5193.4 2955.7 2982.3 2991.4 2997.4 3007.4 4839.7 5243.2 5586.8 5672.3 5941.7 4227.0 5025.9 5338.7 5495.6 5749.8 5179.0 5644.3 5860.4 5862.5 5883.0 4965.0 5585.0 5634.4 5681.5 5685.6 5009.3 5150.8 5643.5 5660.7 5692.8 5262.4 5803.1 5982.9 5997.1 6000.7 3271.7 3606.8 3779.0 3780.2 3805.0 5062.9 5418.5 5589.6 5678.6 5710.5 5022.8 5128.6 5163.6 5359.3 5492.6 4690.8 5201.6 5493.4 5508.9 5513.5 5141.5 5533.8 5558.6 5579.5 5588.8 5398.5 5501.8 5556.4 5595.9 5681.3 4802.3 4819.9 4901.8 5360.5 5542.9 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:19183.10 Median:50203.25 stddev:26528.92 (52.8%) stddev-sans-first:26566.86 16:36:13 INFO - PROCESS | 1660 | Values: 653.3 683.1 703.3 826.7 827.5 13932.4 14232.5 14827.7 15063.5 15370.0 13641.9 14248.0 14255.6 14260.6 14267.9 703.8 733.1 868.2 879.4 898.7 877.7 893.1 894.3 894.7 899.4 954.6 957.3 959.2 961.0 961.5 748.8 769.6 774.8 775.4 956.0 672.7 696.2 697.1 697.4 697.4 12137.8 14374.6 14517.4 14541.9 14553.3 13411.5 13438.4 13581.0 13596.9 13605.0 11269.4 11361.0 12094.4 12173.6 12302.7 4506.0 4522.6 4578.8 4625.3 4665.3 78846.2 78874.0 78955.0 79103.9 79280.0 8851.3 8852.4 10668.9 10731.7 10798.2 20623.3 20719.4 20742.0 20745.5 20809.9 7774.7 7807.6 7853.2 7888.3 8051.3 4259.5 4316.7 4317.8 4337.4 4343.2 3759.7 3926.3 3938.5 3939.0 3978.5 738.1 745.0 745.3 747.7 748.9 565.1 617.0 665.4 676.6 685.4 9679.7 9682.4 9684.4 9696.8 9727.3 664.5 684.8 687.7 688.4 702.4 3931.3 4329.0 4358.2 4426.5 4508.4 94353.5 95260.0 98883.5 99024.0 99028.0 78626.2 87309.6 87444.0 87683.6 87988.0 74719.0 75143.6 75931.6 78567.0 78754.6 8721.9 8741.5 8758.1 8823.0 8922.8 49388.8 49524.8 49876.8 50010.2 50396.2 59197.4 62373.7 64106.7 64571.7 68477.3 12574.3 13126.9 13240.3 13336.5 13370.3 11948.3 14363.2 14418.6 14530.3 14749.6 10940.3 13464.6 13526.9 13658.4 13847.2 13238.2 13249.3 13629.6 13823.7 14086.2 12917.7 12936.9 13046.5 13166.2 13171.3 10932.2 11175.1 11285.9 11316.8 11561.5 9182.9 10977.7 10993.8 11300.6 11312.0 16:36:13 INFO - PROCESS | 1660 | -------- Summary: end -------- 16:36:13 INFO - PROCESS | 1660 | 16:36:13 INFO - TEST-INFO | 1660: exit 0 16:36:13 INFO - TEST-OK | dromaeo_css | took 1115538ms 16:36:13 INFO - TEST-START | kraken 16:36:13 INFO - operating with platform_type : mac_ 16:36:13 INFO - Initialising browser for kraken test... 16:36:13 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmpBf6OWN/profile http://localhost:49275/getInfo.html) 16:36:16 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 16:36:16 INFO - PROCESS | 1670 | colorDepth:24 16:36:16 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 16:36:16 INFO - PROCESS | 1670 | __metrics 16:36:16 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:36:16 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:36:17 INFO - TEST-INFO | 1670: exit 0 16:36:17 INFO - Browser initialized. 16:36:17 INFO - Running cycle 1/1 for kraken test... 16:36:17 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/tmpBf6OWN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:36:29 INFO - PROCESS | 1671 | RSS: Main: 241561600 16:36:29 INFO - PROCESS | 1671 | 16:36:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:36:33 INFO - PROCESS | 1671 | RSS: Main: 292335616 16:36:33 INFO - PROCESS | 1671 | 16:36:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:36:36 INFO - PROCESS | 1671 | RSS: Main: 301383680 16:36:36 INFO - PROCESS | 1671 | 16:36:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:36:40 INFO - PROCESS | 1671 | RSS: Main: 389976064 16:36:40 INFO - PROCESS | 1671 | 16:36:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:36:42 INFO - PROCESS | 1671 | RSS: Main: 290181120 16:36:42 INFO - PROCESS | 1671 | 16:36:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:36:45 INFO - PROCESS | 1671 | RSS: Main: 293855232 16:36:45 INFO - PROCESS | 1671 | 16:36:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:48 INFO - PROCESS | 1671 | RSS: Main: 334077952 16:36:48 INFO - PROCESS | 1671 | 16:36:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:52 INFO - PROCESS | 1671 | RSS: Main: 335544320 16:36:52 INFO - PROCESS | 1671 | 16:36:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:36:56 INFO - PROCESS | 1671 | RSS: Main: 335519744 16:36:56 INFO - PROCESS | 1671 | 16:36:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:58 INFO - PROCESS | 1671 | RSS: Main: 311488512 16:36:58 INFO - PROCESS | 1671 | 16:37:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:37:00 INFO - PROCESS | 1671 | RSS: Main: 317333504 16:37:00 INFO - PROCESS | 1671 | 16:37:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:37:03 INFO - PROCESS | 1671 | RSS: Main: 322990080 16:37:03 INFO - PROCESS | 1671 | 16:37:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:37:07 INFO - PROCESS | 1671 | RSS: Main: 324325376 16:37:07 INFO - PROCESS | 1671 | 16:37:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:37:10 INFO - PROCESS | 1671 | RSS: Main: 324734976 16:37:10 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:37:12 INFO - PROCESS | 1671 | RSS: Main: 368369664 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | __start_tp_report 16:37:12 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:37:12 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:37:12 INFO - PROCESS | 1671 | |i|pagename|runs| 16:37:12 INFO - PROCESS | 1671 | |0;ai-astar;113;106;113;119;166;156;103;154;167;157 16:37:12 INFO - PROCESS | 1671 | |1;audio-beat-detection;153;123;118;171;120;115;136;116;148;118 16:37:12 INFO - PROCESS | 1671 | |2;audio-dft;184;186;183;184;184;182;184;183;180;182 16:37:12 INFO - PROCESS | 1671 | |3;audio-fft;80;75;57;110;57;58;65;60;96;56 16:37:12 INFO - PROCESS | 1671 | |4;audio-oscillator;85;90;92;71;72;69;96;70;69;99 16:37:12 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;90;85;89;85;89;86;89;85;89;85 16:37:12 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;94;95;94;94;93;94;93;93;94 16:37:12 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;86;85;84;83;103;132;116;132;107 16:37:12 INFO - PROCESS | 1671 | |8;json-parse-financial;56;54;55;54;58;55;58;56;54;55 16:37:12 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;70;68;76;70;70;72;69;69;71 16:37:12 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;82;82;87;78;99;98;107;108;118;85 16:37:12 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;140;139;126;138;141;142;138;140;152;143 16:37:12 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;156;165;168;168;166;162;164;168;164;162 16:37:12 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;67;66;70;76;83;85;84;68;66 16:37:12 INFO - PROCESS | 1671 | __end_tp_report 16:37:12 INFO - PROCESS | 1671 | __start_cc_report 16:37:12 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1930 16:37:12 INFO - PROCESS | 1671 | __end_cc_report 16:37:12 INFO - PROCESS | 1671 | __startTimestamp1464737832618__endTimestamp 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:37:12 INFO - PROCESS | 1671 | Number of tests: 14 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:135.40 Median:155.00 stddev:26.58 (17.1%) stddev-sans-first:26.93 16:37:12 INFO - PROCESS | 1671 | Values: 113.0 106.0 113.0 119.0 166.0 156.0 103.0 154.0 167.0 157.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:129.50 stddev:19.42 (15.0%) stddev-sans-first:19.03 16:37:12 INFO - PROCESS | 1671 | Values: 153.0 123.0 118.0 171.0 120.0 115.0 136.0 116.0 148.0 118.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.00 stddev:1.62 (0.9%) stddev-sans-first:1.69 16:37:12 INFO - PROCESS | 1671 | Values: 184.0 186.0 183.0 184.0 184.0 182.0 184.0 183.0 180.0 182.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:71.40 Median:62.50 stddev:18.81 (30.1%) stddev-sans-first:19.69 16:37:12 INFO - PROCESS | 1671 | Values: 80.0 75.0 57.0 110.0 57.0 58.0 65.0 60.0 96.0 56.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:87.50 stddev:12.28 (14.0%) stddev-sans-first:12.95 16:37:12 INFO - PROCESS | 1671 | Values: 85.0 90.0 92.0 71.0 72.0 69.0 96.0 70.0 69.0 99.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:89.00 stddev:2.15 (2.4%) stddev-sans-first:2.03 16:37:12 INFO - PROCESS | 1671 | Values: 90.0 85.0 89.0 85.0 89.0 86.0 89.0 85.0 89.0 85.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 16:37:12 INFO - PROCESS | 1671 | Values: 93.0 94.0 95.0 94.0 94.0 93.0 94.0 93.0 93.0 94.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:83.50 stddev:19.82 (23.7%) stddev-sans-first:20.13 16:37:12 INFO - PROCESS | 1671 | Values: 85.0 86.0 85.0 84.0 83.0 103.0 132.0 116.0 132.0 107.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:55.50 Median:55.50 stddev:1.51 (2.7%) stddev-sans-first:1.59 16:37:12 INFO - PROCESS | 1671 | Values: 56.0 54.0 55.0 54.0 58.0 55.0 58.0 56.0 54.0 55.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:2.83 (4.0%) stddev-sans-first:2.35 16:37:12 INFO - PROCESS | 1671 | Values: 65.0 70.0 68.0 76.0 70.0 70.0 72.0 69.0 69.0 71.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:83.50 stddev:13.56 (16.2%) stddev-sans-first:13.62 16:37:12 INFO - PROCESS | 1671 | Values: 82.0 82.0 87.0 78.0 99.0 98.0 107.0 108.0 118.0 85.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:140.50 stddev:6.35 (4.5%) stddev-sans-first:6.74 16:37:12 INFO - PROCESS | 1671 | Values: 140.0 139.0 126.0 138.0 141.0 142.0 138.0 140.0 152.0 143.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:165.50 stddev:3.71 (2.2%) stddev-sans-first:2.44 16:37:12 INFO - PROCESS | 1671 | Values: 156.0 165.0 168.0 168.0 166.0 162.0 164.0 168.0 164.0 162.0 16:37:12 INFO - PROCESS | 1671 | 16:37:12 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.00 stddev:8.10 (11.1%) stddev-sans-first:8.18 16:37:12 INFO - PROCESS | 1671 | Values: 66.0 67.0 66.0 70.0 76.0 83.0 85.0 84.0 68.0 66.0 16:37:12 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:37:12 INFO - PROCESS | 1671 | 16:37:13 INFO - TEST-INFO | 1671: exit 0 16:37:13 INFO - TEST-OK | kraken | took 59462ms 16:37:13 INFO - SUITE-END | took 1175s 16:37:13 INFO - Completed test suite (00:19:35) 16:37:13 INFO - javascript benchmark 16:37:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3218.4045951498965, 3424.5205671206036, 3455.7580969332157, 3482.094655389598, 3521.4189658706314, 5541.750621222044, 5611.523020543254, 5622.4939755422, 5667.829965101048, 5716.22830917826, 5597.63615365002, 5640.802571909973, 5739.110437882125, 5747.655140691561, 5790.015697331453, 5060.418051924021, 5081.263216833557, 5142.948570514284, 5154.793808247684, 5195.532402083812, 5195.324607800981, 5203.349581302337, 5217.452167522412, 5226.869328266805, 5260.8158714445, 5076.13705670037, 5094.133997220481, 5098.056859481007, 5115.948840511584, 5190.896182076356, 5105.744712764347, 5123.897522049576, 5141.691498510064, 5183.792648294064, 5187.429382767892, 5082.466341034212, 5126.128558145087, 5141.794328226866, 5148.84553463393, 5155.510226528511, 5501.449855014466, 5579.107342825169, 5611.831645050655, 5658.971705141489, 5669.546436285129, 6240.656763877985, 6248.218972628421, 6254.718537665816, 6266.811995640138, 6266.905996410058, 6259.436650701458, 6322.968385158091, 6336.96831515844, 6339.651319177444, 6344.777932772331, 5620.381758006616, 5691.231683722686, 5741.339745929272, 5764.769409223668, 5765.94234057654, 6370.140031095835, 6380.234371875405, 6390.45681117101, 6391.712372943229, 6397.936020639827, 5908.20239267702, 6125.785597504066, 6134.6625935573975, 6137.478314343241, 6142.57002009855, 6138.75626760427, 6201.224846894138, 6223.346548612421, 6253.155823963798, 6253.968730156411, 6124.5100391968535, 6145.631262124198, 6168.8766224675255, 6195.1636529068, 6197.628142311476, 6065.5754097212775, 6112.449879510866, 6224.968875155686, 6226.719797609118, 6233.750649974041, 4753.809847606126, 4803.0, 4813.663043586986, 4821.493743156988, 4886.682365646222, 4296.61330480252, 4310.956890431118, 4314.288142456459, 4315.352697095461, 4353.455818022747, 5114.3095682083185, 5232.895342093137, 5429.131338985743, 5479.0959491684225, 5484.424135465799, 6106.023036314168, 6168.0, 6235.314115447297, 6251.156093927352, 6298.338674439209, 5593.524550413261, 5637.238972738628, 5644.294463192101, 5657.717114144276, 5684.289463817023, 5293.682379057192, 5389.9191512127345, 5393.271908292409, 5399.136138217867, 5401.5948803839865, 6145.416185462464, 6247.562670613104, 6291.056341548621, 6311.368863113652, 6338.683065846782, 6244.65654389013, 6250.687465626609, 6269.0909818075215, 6270.7805226816845, 6280.340564240689, 6139.829471381233, 6326.177596912372, 6330.461910737639, 6339.778107766206, 6341.112244285711, 6100.127044624312, 6130.785422510191, 6145.863015342176, 6154.41533054377, 6154.723037463415, 6138.508919286535, 6213.254409470893, 6213.254409470893, 6227.532935029982, 6262.5929314594405, 5956.086228861121, 6145.525073982301, 6164.044573091178, 6168.444839964424, 6182.969085154546, 6134.509239260938, 6141.109539116893, 6151.0, 6163.3836616338, 6165.907511387422, 5789.56578256624, 5829.15477255804, 5859.912101318569, 5955.731992060281, 5966.284045914518, 6145.539084568586, 6150.50795936333, 6163.96918015407, 6166.599171053867, 6168.444839964245, 6028.388282963624, 6038.033914573647, 6045.365236650088, 6051.152838602688, 6059.454649081604], "value": 5673.518751076204, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.27397998921595, 514.5009340939289, 522.7752066611356, 527.4093015822278, 547.7480359034844, 29809.9566515172, 41312.0, 42165.367519487234, 42201.36697949533, 42217.788911055475, 42895.57104428955, 43429.565704342946, 43504.347434788506, 43534.0, 43655.56344436554, 9297.67458138967, 9529.237660987139, 9545.284103692251, 9546.28402869784, 9553.140217380434, 9374.0, 9541.427514349127, 9543.522823858813, 9565.186959108449, 9570.234381249518, 9551.519514475232, 9580.425174489517, 9583.329166958316, 9586.424814511116, 9586.808263834719, 9521.857172142423, 9554.04459554046, 9561.808763824754, 9564.04359564038, 9581.616735330543, 9312.813743725155, 9421.811563768686, 9428.15146636806, 9430.905690943071, 9459.337846350758, 40233.396499052535, 40629.39055914163, 40647.7967610163, 40676.796616017025, 40685.389719154235, 2819.901303454369, 3473.1490784757643, 3524.242287908112, 3576.517170182018, 3693.132113953219, 30618.0, 31119.533207001914, 31544.526832097537, 31636.209094772814, 32269.354612907846, 3095.3197453369035, 3199.600049993751, 3251.561039259717, 3275.3121844412703, 3400.96599034009, 504135.0, 505745.99999999633, 506166.0, 507234.92765072244, 508402.0, 64833.675831621484, 65405.672971634835, 65444.0, 65472.69054618881, 65680.34319656838, 34347.65652343495, 34575.0, 35305.0, 36921.81539092287, 68764.96852547115, 16492.83507164913, 16578.75131873023, 16739.246733897005, 16739.414120505968, 16750.664986700198, 12686.619401417787, 12789.552365667158, 12792.680182995313, 12800.551980680631, 12813.231206127475, 19809.0, 20010.0, 20011.0, 20020.69968950467, 20042.0, 7937.515303853304, 8016.919830801734, 8067.233612806774, 8074.555899425588, 8095.935536438288, 838.7944953486435, 945.9858102128474, 1012.6810054832704, 1059.279689810936, 1092.5192915117323, 30280.54579181314, 30384.088477345715, 30924.536131958037, 31027.844860775553, 31317.0, 864.7751584587927, 1034.6792494326783, 1102.162356608983, 1162.7965106106567, 1189.2923710392304, 15613.531594052196, 15616.843831561766, 15663.68672626541, 15994.520164395086, 16107.114108724132, 650243.0, 663150.0, 668496.0, 669319.0, 669982.0, 42383.0, 42557.36163957543, 42639.147217055484, 42647.720568382996, 42747.717568472995, 1305.5822136916286, 1340.8659134086513, 1413.4063693249066, 1432.5917113622568, 1462.648964248594, 28129.859350703115, 30309.848450757607, 30777.076687699406, 31257.749690012144, 31881.36237275288, 42544.0, 51205.231921521954, 51821.7408912953, 51919.702007448606, 52095.47904521058, 482883.0, 497604.51197743777, 497785.0, 497854.0, 499308.0, 23614.409639759142, 23630.29109126729, 23973.640395394432, 26971.0, 27473.31316717098, 40434.59565404426, 48790.0, 49458.505414945386, 51230.0, 52125.7393713029, 38219.617803822715, 39825.00437489086, 40363.394549082375, 40468.05518416095, 40674.79662601668, 38372.84881453561, 38659.64691235793, 39228.0, 39421.60578394294, 39819.0, 32224.677753222168, 32554.348913021135, 32674.83662581672, 33224.66775332216, 38607.42088868725, 32239.194020149684, 34333.82833085819, 34853.825730872195, 34916.82541587276, 34964.82517587396, 25167.49665006653, 25379.74620253774, 25416.745832541437, 25551.744482554936, 25878.964841406134], "value": 19706.422883631967, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.1107835361139, 387.7499013136527, 387.7692772800183, 401.02350775860765, 405.7058632491444, 19239.326623568177, 20921.47696307593, 21162.365129046153, 21288.8291143987, 21331.68002479964, 20127.49681257969, 20536.8973155134, 20943.37169884906, 20980.475488112806, 21186.57626847466, 5030.094582975063, 5695.77216911323, 5718.799842005531, 5863.442972917587, 5868.383819698913, 5818.389069147742, 5826.242588463515, 5838.0, 5900.262467191601, 5919.348871624118, 5620.409856965021, 5958.225430694004, 5975.462208401243, 5996.070609055603, 6015.639061656293, 5837.766489340422, 5851.590388672795, 5915.940840591604, 5925.170476133323, 5930.5552083593475, 5580.776768929238, 5665.320161580596, 5730.111832665943, 5731.971340143273, 5743.597948143632, 11260.718482037933, 13118.606441806762, 13332.133411328232, 13430.261335626537, 13479.326033698373, 2175.2930297653324, 2179.934601961944, 2228.765979572154, 2242.6411774116227, 2300.919467818618, 9882.703518894445, 9907.603695852158, 9926.511023346484, 10162.390256584555, 10488.213383996155, 1808.7106063029983, 1902.7526421565246, 1904.9141989066238, 1915.8467322614158, 1997.0813425823992, 119154.0, 125247.0, 125735.0, 127675.0, 128277.0, 10026.248031397674, 11151.721206969729, 11435.256708313933, 11449.141314401453, 11498.885011149781, 13877.583672489842, 14541.854581454261, 14644.707105857824, 14649.267536623127, 14655.706885862437, 8372.28835548985, 8412.621432035574, 8440.957795211107, 8556.486610803371, 8568.314534837198, 7031.8945215821805, 8057.59712014397, 8208.302294305051, 8247.876281855777, 8344.41589088758, 4376.846810361622, 4441.933370999438, 4546.0, 4552.362669226311, 4634.281686338623, 1600.8559229669386, 1722.7588137660737, 1819.2359209132196, 1853.3698542495347, 1938.2053358123103, 735.7866218796596, 893.4549924546021, 918.9632414703337, 978.8238117138841, 1072.9088027517748, 18545.44363669092, 19658.0, 19988.50028749293, 20269.087891044644, 20767.065482053345, 848.3552500099845, 938.1978408460805, 960.2893858544627, 963.8409662405625, 974.1865542272141, 8299.502029878227, 10267.229957753198, 10684.0, 10692.893071069346, 10696.411697356565, 564224.0, 570705.0, 583162.0, 591345.0, 592021.0, 125273.0, 130986.34506827405, 133397.0, 133683.99474007898, 137556.0, 877.8463768840427, 1024.3546565663585, 1052.0, 1073.4900922062113, 1081.1837063017372, 17152.228149733044, 17486.73769893427, 17785.377511787025, 17790.644187116442, 18209.817901820814, 37253.6274637261, 51136.74431627818, 51569.96860062703, 51971.48028519817, 53753.46246537485, 74558.0, 75318.62340688262, 75899.0, 78377.216227837, 78476.21523784689, 7380.96309518449, 8124.959375203086, 8189.549574773445, 8244.0, 8365.372597055124, 9928.453935033642, 12254.080943929419, 12614.810777838524, 12823.871761282267, 12823.974082073599, 10746.677599672023, 11065.170112241452, 11067.00396964277, 11136.777264454828, 11286.153538484483, 10609.840852387375, 10940.39827809478, 11023.0630396414, 11154.330740155616, 11188.384638844942, 11237.55049797999, 11269.718257043312, 11297.0, 11355.829662554903, 11399.658010259374, 7021.4031807294905, 7411.7035318588805, 8067.273945344947, 8194.22154895284, 8315.708950186714, 10544.578216871547, 10577.471126443801, 11223.102151827998, 11274.943625281821, 11366.772664546497], "value": 9288.825724176033, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.916005039697, 1544.714227867844, 1572.7483602623581, 1808.61114860305, 1972.9641937982567, 7946.682132714692, 8702.26030787383, 8919.95540022299, 8922.687705930286, 9050.63797448103, 8639.956800216007, 8878.42290251135, 8898.37711360205, 8906.732798016053, 8907.28741700664, 2081.6469294958247, 2154.9784502154935, 2158.665406861939, 2342.472943587692, 2376.786089251967, 2187.660912558556, 2213.6679498075337, 2347.788699017088, 2425.708914930202, 2437.8293519453646, 2396.2571602803187, 2397.61638137898, 2397.940051498709, 2398.2325655790155, 2400.4838959623676, 2346.4044449774146, 2393.7366889642126, 2395.796357309631, 2395.940101497459, 2396.8681722505344, 2108.9156433742633, 2188.7482939462006, 2189.627763280246, 2193.670949357593, 2329.9534009319887, 8628.09405012471, 8721.21509064181, 8810.207081362709, 8813.162749538784, 8858.424202426822, 5284.603654725911, 5462.262594549725, 5463.890722185534, 5466.863328416782, 5474.562035037187, 3563.572371315433, 3578.964210357889, 3597.190632107781, 3636.9454458182886, 3666.7799932004164, 1058.6612284069101, 1073.1200415659162, 1092.8524649172339, 1100.4827730966513, 1115.2249186815168, 11462.598809041727, 11704.590339338167, 11733.061355091571, 11734.647960561197, 11736.823947640793, 4773.362619808279, 4784.2823576463115, 4875.683080599821, 4894.559489645949, 4916.262560615936, 10179.54192061359, 10215.336003159771, 10223.386596804216, 10235.027672371112, 10243.231757618209, 5431.266778984866, 5436.21174929633, 5436.945630543723, 5442.102053161184, 5461.1808228766, 4463.687541872103, 4772.761361931959, 4781.760911954389, 4879.95120048795, 4943.307936888767, 3831.885043448701, 3950.743201691881, 3957.5053118360206, 3963.3460479021214, 4015.4579131817413, 1160.8316794064813, 1292.9418176182094, 1303.648015035935, 1316.8090626859052, 1329.1360615599745, 847.559269180023, 855.7005048233193, 931.6040682709821, 946.990530094704, 950.8352268471068, 6151.0, 6195.101710251988, 6258.311585725566, 6268.247810262797, 6340.778072767431, 943.7499062748377, 993.5231089077283, 1014.0569270578286, 1019.5819713917258, 1030.3817709374316, 3467.7052450541987, 4063.4514340563646, 4138.606832350922, 4148.2947898857265, 4215.557366476537, 10448.582056717798, 10956.452177391098, 10959.780804383867, 10966.835497467544, 10983.835242471368, 10760.730981725363, 10896.291741036806, 10902.5093870776, 10908.236423450282, 10917.508712107974, 8050.355971522381, 8079.272865442138, 8180.877286840702, 8192.426530142951, 8205.425620206648, 4851.660383773102, 5846.327672317738, 5878.764849406147, 6013.278406591237, 6059.151718759465, 9679.951600241953, 9883.901160988298, 9922.00779922002, 10085.495725213563, 10091.596336146471, 10013.549390277309, 10128.03783640553, 10167.339122956984, 10209.846852297072, 10221.233407494616, 7126.643667816692, 7169.964150179216, 7278.67245973943, 7280.963595182202, 7287.708491660274, 7479.551226926402, 7760.573168475788, 8156.510609363457, 8402.495850249004, 8409.747707568538, 6734.528582999143, 7082.468814838805, 7333.559986400833, 7505.88741168872, 7716.729914452967, 7202.747903823341, 7310.342069213796, 7416.332530072319, 7446.404287657083, 7526.209747976383, 6961.477889158232, 7045.048918395951, 7116.395106416013, 7134.785956421316, 7134.821629459305, 4526.2531682273375, 4659.394278743877, 5040.42035165946, 5060.873478163076, 5061.924071139009, 5005.174146265826, 5058.924116138334, 5106.387233532, 5126.179811230185, 5158.3552055993005], "value": 4615.405115693941, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.6308429884139, 312.16340208241905, 316.6263808705728, 319.18925928142505, 322.4212538493404, 4651.813927442907, 5334.653247538912, 5364.865878353034, 5386.175815171228, 5427.267318911946, 4105.486814148231, 5325.81641526974, 5352.384475785286, 5356.464353564651, 5488.780448782054, 3510.2803925195403, 3559.448285515736, 3814.5396219438444, 3968.8610898618554, 4010.5788892166192, 3982.4822773039464, 3983.4821473208444, 4040.4949381327333, 4061.5784475433657, 4064.9024763313887, 3567.696745776599, 4012.117334186474, 4020.959790402088, 4027.2750904837117, 4035.051762835731, 3732.7976493868746, 3825.674817640504, 3935.701218597856, 3961.2473630010495, 4037.0916543777707, 3018.592490013842, 3479.634638362986, 3503.7897726136257, 3509.7364948618733, 3552.875649352286, 4051.4327994080854, 5056.393232812086, 5099.184130539133, 5108.2848401223855, 5126.334306160813, 3799.145192331731, 4084.856240252734, 4559.475660299042, 4595.678302518825, 4648.930266046012, 4856.1016212000895, 4912.754362281908, 5526.1264142646005, 5544.1960915667005, 5560.277163968699, 3788.90527736806, 3792.9190180798614, 3793.8103094845424, 3801.4414090222876, 3806.771593704359, 4591.471980722193, 5596.132599447052, 5819.388964158787, 5839.729751339263, 5842.649441033552, 3390.7965522068753, 3473.4616134498688, 3850.9807450962567, 3853.420097759905, 3862.343401621731, 3261.510773383964, 3607.3687104756564, 3902.20004898995, 3909.706771992112, 3928.842846286174, 4558.840440584564, 4568.1548913451115, 4572.199865023607, 4585.495595484494, 4585.564371384779, 4282.700210985264, 5311.946880531223, 5312.893742125136, 5562.721863906789, 5632.8028519002455, 1229.6065259117177, 1296.027979015738, 1326.6733266733268, 1326.8076128961247, 1330.9286024749977, 702.9736584586459, 778.0196951840721, 799.2287442617902, 818.4434584482609, 819.4468733604793, 4344.891377715582, 4352.499462561783, 4526.524714904954, 4951.72765497901, 5034.496550345009, 4734.467060122818, 4771.900363839899, 5533.592887094127, 5660.8867822643315, 5754.0, 4604.401427814362, 5170.767315470813, 5173.3533308336455, 5182.015417070745, 5193.350831146106, 2955.6896525864477, 2982.2991596974693, 2991.3867657130236, 2997.415503976747, 3007.3985202959057, 4839.661223714376, 5243.187305967581, 5586.804461843816, 5672.290963629546, 5941.673207973517, 4226.957730422657, 5025.899482010413, 5338.679679219259, 5495.587830912697, 5749.751390464374, 5178.97410512945, 5644.266245388156, 5860.384659610765, 5862.501687356623, 5882.970270310793, 4964.982178653295, 5584.972075139598, 5634.4365563443325, 5681.517071049007, 5685.602007859576, 5009.323741294878, 5150.793968241228, 5643.52030077448, 5660.7169641518585, 5692.829215123553, 5262.394824595068, 5803.100519419497, 5982.850428739316, 5997.130416089555, 6000.669963152069, 3271.7382609391666, 3606.8016259106003, 3778.962210377861, 3780.2250538640064, 3804.9619503804606, 5062.949370506248, 5418.45815418455, 5589.552835773047, 5678.574106941914, 5710.5146062585145, 5022.773975171199, 5128.564072053768, 5163.586913047023, 5359.276497672764, 5492.642978206404, 4690.765461726832, 5201.583873290203, 5493.423190564933, 5508.925759476863, 5513.476219759116, 5141.485851414828, 5533.750981205936, 5558.583106267127, 5579.46995035471, 5588.832335029955, 5398.541124004503, 5501.779928802963, 5556.444355564411, 5595.944040559542, 5681.346645135863, 4802.27965805132, 4819.927701084416, 4901.779419926041, 5360.490753378423, 5542.916856247079], "value": 3999.7291938366884, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [653.2911790707086, 683.1358331710384, 703.3072423662693, 826.6941231744255, 827.5159031966299, 13932.373043213054, 14232.501862434803, 14827.703445931074, 15063.548093557198, 15370.0, 13641.863581364209, 14248.0, 14255.572332830006, 14260.643483912907, 14267.857321426782, 703.8416356319813, 733.1275781819644, 868.2402897464718, 879.3712495565669, 898.7124120281512, 877.6577134917387, 893.0801274687083, 894.3292530602048, 894.6645008121955, 899.4198741811527, 954.5513608603945, 957.2724729205784, 959.2326139088736, 960.9903900961006, 961.5048250151178, 748.8127968007998, 769.5536588778496, 774.8450309938034, 775.4068137874474, 956.0391806234725, 672.7409947170325, 696.223710562724, 697.1146643762394, 697.3619138488277, 697.3793323941696, 12137.846904544054, 14374.568762937128, 14517.419303227858, 14541.854581454261, 14553.345099470549, 13411.46354145833, 13438.395272212774, 13580.981426392962, 13596.864031359659, 13605.0, 11269.380184089872, 11361.0, 12094.395280236042, 12173.573924912675, 12302.692432689164, 4505.954940450619, 4522.6155776759015, 4578.793954272066, 4625.259958406638, 4665.300204969248, 78846.21153788446, 78874.0, 78955.0, 79103.85824921362, 79280.0, 8851.33614978879, 8852.3803333766, 10668.89331106679, 10731.658542682164, 10798.190135739851, 20623.278185263443, 20719.378418647466, 20741.962901854848, 20745.481362966046, 20809.89595052015, 7774.727884524128, 7807.570583617956, 7853.243633348775, 7888.2506161914525, 8051.275385215359, 4259.467566554181, 4316.719413238129, 4317.762523061262, 4337.436133302651, 4343.218220720238, 3759.6992240620684, 3926.293267211929, 3938.5470670873215, 3939.0, 3978.542467616261, 738.092146659601, 744.9813754656069, 745.3478206569251, 747.6971826410313, 748.8689479341093, 565.0535353283234, 616.9537284703665, 665.4377051391566, 676.5839009009424, 685.413971054751, 9679.66121185755, 9682.370645907993, 9684.41893486393, 9696.806063878683, 9727.270454715925, 664.5016237821634, 684.8253695307665, 687.6836655138692, 688.4148473797233, 702.3959394920364, 3931.253061918226, 4328.978355108267, 4358.171947329997, 4426.535213802569, 4508.391367165456, 94353.52823235978, 95260.0, 98883.50558247163, 99024.0, 99028.00971990332, 78626.2137378619, 87309.56345218234, 87444.0, 87683.56158219295, 87988.0, 74719.0, 75143.57582247924, 75931.62034189794, 78567.0, 78754.60622696964, 8721.869171962553, 8741.470242707628, 8758.080401558, 8822.955885220534, 8922.776930576787, 49388.753056235924, 49524.75237623789, 49876.75061624814, 50010.249641263836, 50396.244056339914, 59197.40802591919, 62373.68813156087, 64106.67946660237, 64571.67714161399, 68477.31522684709, 12574.30841303737, 13126.934365328112, 13240.337983100613, 13336.461146077376, 13370.264635445144, 11948.34284114382, 14363.21002344881, 14418.639534011734, 14530.273486325852, 14749.631259218177, 10940.343579385262, 13464.596062118153, 13526.864731352955, 13658.385372658055, 13847.169169849842, 13238.205707657571, 13249.337533123498, 13629.591112266648, 13823.723525529635, 14086.22525761093, 12917.741645166856, 12936.935315323362, 13046.478140874258, 13166.210027398387, 13171.2755798432, 10932.180086493703, 11175.10599152082, 11285.887141128813, 11316.773664526829, 11561.479733411848, 9182.908170918205, 10977.670669879917, 10993.835092473459, 11300.604478843201, 11312.0], "value": 7371.015941982031, "unit": "score"}], "name": "dromaeo_css", "value": 7217.261788265785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 113.0, 119.0, 166.0, 156.0, 103.0, 154.0, 167.0, 157.0], "value": 135.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 118.0, 171.0, 120.0, 115.0, 136.0, 116.0, 148.0, 118.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 183.0, 184.0, 184.0, 182.0, 184.0, 183.0, 180.0, 182.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 57.0, 110.0, 57.0, 58.0, 65.0, 60.0, 96.0, 56.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 92.0, 71.0, 72.0, 69.0, 96.0, 70.0, 69.0, 99.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 89.0, 85.0, 89.0, 86.0, 89.0, 85.0, 89.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 94.0, 93.0, 94.0, 93.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 84.0, 83.0, 103.0, 132.0, 116.0, 132.0, 107.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 54.0, 58.0, 55.0, 58.0, 56.0, 54.0, 55.0], "value": 55.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 68.0, 76.0, 70.0, 70.0, 72.0, 69.0, 69.0, 71.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 87.0, 78.0, 99.0, 98.0, 107.0, 108.0, 118.0, 85.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 126.0, 138.0, 141.0, 142.0, 138.0, 140.0, 152.0, 143.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 165.0, 168.0, 168.0, 166.0, 162.0, 164.0, 168.0, 164.0, 162.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 70.0, 76.0, 83.0, 85.0, 84.0, 68.0, 66.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1482.5, "alertThreshold": 2.0}]} 16:37:13 INFO - Return code: 0 16:37:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:37:13 ERROR - # TBPL SUCCESS # 16:37:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:37:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:37:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:37:13 INFO - 2016-05-31 16:37:13.953 screenresolution[1675:9613] starting screenresolution argv=screenresolution get 16:37:13 INFO - 2016-05-31 16:37:13.956 screenresolution[1675:9613] Display 0: 1600x1200x32@60 16:37:13 INFO - 2016-05-31 16:37:13.961 screenresolution[1676:9616] starting screenresolution argv=screenresolution list 16:37:13 INFO - Available Modes on Display 0 16:37:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:37:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:37:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:37:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:37:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:37:14 INFO - Intel Iris: 16:37:14 INFO - Chipset Model: Intel Iris 16:37:14 INFO - Type: GPU 16:37:14 INFO - Bus: Built-In 16:37:14 INFO - VRAM (Dynamic, Max): 1536 MB 16:37:14 INFO - Vendor: Intel (0x8086) 16:37:14 INFO - Device ID: 0x0a2e 16:37:14 INFO - Revision ID: 0x0009 16:37:14 INFO - Displays: 16:37:14 INFO - Display: 16:37:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:37:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:37:14 INFO - Display Serial Number: 1600x1200 60 16:37:14 INFO - Main Display: Yes 16:37:14 INFO - Mirror: Off 16:37:14 INFO - Online: Yes 16:37:14 INFO - Rotation: Supported 16:37:14 INFO - Return code: 0 16:37:14 INFO - Running post-action listener: _resource_record_post_action 16:37:14 INFO - ##### 16:37:14 INFO - ##### Finished run-tests step (success) 16:37:14 INFO - ##### 16:37:14 INFO - Running post-run listener: _resource_record_post_run 16:37:14 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36771328; Write bytes: 695849984; Read time: 241; Write time: 1898 16:37:14 INFO - TinderboxPrint: CPU usage
53.8% 16:37:14 INFO - TinderboxPrint: I/O read bytes / time
36,771,328 / 241 16:37:14 INFO - TinderboxPrint: I/O write bytes / time
695,849,984 / 1,898 16:37:14 INFO - TinderboxPrint: CPU idle
2,240.8 (46.2%) 16:37:14 INFO - TinderboxPrint: CPU system
969.3 (20.0%) 16:37:14 INFO - TinderboxPrint: CPU user
1,635.6 (33.8%) 16:37:14 INFO - TinderboxPrint: Swap in / out
435,822,592 / 0 16:37:14 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376398336; Write bytes: 371181056; Read time: 28008; Write time: 761 16:37:14 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35816960; Write bytes: 294321664; Read time: 228; Write time: 1069 16:37:14 INFO - Running post-run listener: _upload_blobber_files 16:37:14 INFO - Blob upload gear active. 16:37:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:37:14 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 16:37:14 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'] 16:37:14 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 16:37:15 INFO - (blobuploader) - INFO - Open directory for files ... 16:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:37:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:16 INFO - (blobuploader) - INFO - Done attempting. 16:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:17 INFO - (blobuploader) - INFO - Done attempting. 16:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:37:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:20 INFO - (blobuploader) - INFO - Done attempting. 16:37:20 INFO - (blobuploader) - INFO - Iteration through files over. 16:37:20 INFO - Return code: 0 16:37:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:37:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:37:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c030f335c1a30bf8c4d71ba49f99253cca4403b728adf72665933fb3d3f9d7cc31601d994191b91aaca923b32459fcb3c529ba209cef70a95a795155363277d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef89262994cae90c1a760f53a29646ebe1562667d8fb1cbe8894bf17b4c20e2880738b4f75a2ef4de65f89f4f076186c421ce5cdb0253ec73948d04d17857bad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a2ec000792645bbd1af117d3773815d46310bbd39f4b5106fd9550551dc958ef994b9028e9ff0cf9f9205f103f487b90e99c6174ceb4984f355559d0de730bb"} 16:37:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:37:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:37:20 INFO - Contents: 16:37:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c030f335c1a30bf8c4d71ba49f99253cca4403b728adf72665933fb3d3f9d7cc31601d994191b91aaca923b32459fcb3c529ba209cef70a95a795155363277d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef89262994cae90c1a760f53a29646ebe1562667d8fb1cbe8894bf17b4c20e2880738b4f75a2ef4de65f89f4f076186c421ce5cdb0253ec73948d04d17857bad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a2ec000792645bbd1af117d3773815d46310bbd39f4b5106fd9550551dc958ef994b9028e9ff0cf9f9205f103f487b90e99c6174ceb4984f355559d0de730bb"} 16:37:20 INFO - Running post-run listener: copy_logs_to_upload_dir 16:37:20 INFO - Copying logs to upload dir... 16:37:20 INFO - mkdir: /builds/slave/test/build/upload/logs 16:37:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.830545 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-31 16:37:20.868392) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 16:37:20.872463) ========= 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.SYdMSmNf14/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.72KS6XsmtJ/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c030f335c1a30bf8c4d71ba49f99253cca4403b728adf72665933fb3d3f9d7cc31601d994191b91aaca923b32459fcb3c529ba209cef70a95a795155363277d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef89262994cae90c1a760f53a29646ebe1562667d8fb1cbe8894bf17b4c20e2880738b4f75a2ef4de65f89f4f076186c421ce5cdb0253ec73948d04d17857bad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a2ec000792645bbd1af117d3773815d46310bbd39f4b5106fd9550551dc958ef994b9028e9ff0cf9f9205f103f487b90e99c6174ceb4984f355559d0de730bb"} build_url:https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011216 build_url: 'https://queue.taskcluster.net/v1/task/bcWhBtJ_SraLIhmbukj5ug/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c030f335c1a30bf8c4d71ba49f99253cca4403b728adf72665933fb3d3f9d7cc31601d994191b91aaca923b32459fcb3c529ba209cef70a95a795155363277d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ef89262994cae90c1a760f53a29646ebe1562667d8fb1cbe8894bf17b4c20e2880738b4f75a2ef4de65f89f4f076186c421ce5cdb0253ec73948d04d17857bad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0a2ec000792645bbd1af117d3773815d46310bbd39f4b5106fd9550551dc958ef994b9028e9ff0cf9f9205f103f487b90e99c6174ceb4984f355559d0de730bb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 16:37:20.904435) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:37:20.904818) ========= 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.SYdMSmNf14/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.72KS6XsmtJ/Listeners TMPDIR=/var/folders/r5/r29qydxj0vb5gs1yvh8jslnm00000w/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.005322 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:37:20.954093) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 16:37:20.954515) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 16:37:20.955050) ========= ========= Total master_lag: 0.18 =========