builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0316 starttime: 1464611650.12 results: success (0) buildid: 20160530032935 builduid: 18982da13ac846d09057dbf62e0f9603 revision: f03b60a446f193327a21e0e2ca9892538b44b2f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.122809) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.123254) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.127561) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.309780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.310069) ========= 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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners TMPDIR=/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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-30 05:34:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-05-30 05:34:10 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.496453 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.864195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.866738) ========= 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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners TMPDIR=/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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.026319 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:10.981953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 05:34:10.982383) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f03b60a446f193327a21e0e2ca9892538b44b2f9 --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 f03b60a446f193327a21e0e2ca9892538b44b2f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners TMPDIR=/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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-30 05:34:11,077 truncating revision to first 12 chars 2016-05-30 05:34:11,077 Setting DEBUG logging. 2016-05-30 05:34:11,077 attempt 1/10 2016-05-30 05:34:11,077 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f03b60a446f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 05:34:12,748 unpacking tar archive at: fx-team-f03b60a446f1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.951973 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 05:34:12.973602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:12.973956) ========= script_repo_revision: f03b60a446f193327a21e0e2ca9892538b44b2f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:12.974386) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:12.974683) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 05:34:12.991027) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 41 secs) (at 2016-05-30 05:34:12.991455) ========= /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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners TMPDIR=/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:34:13 INFO - MultiFileLogger online at 20160530 05:34:13 in /builds/slave/test 05:34:13 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 05:34:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:34:13 INFO - {'append_to_log': False, 05:34:13 INFO - 'base_work_dir': '/builds/slave/test', 05:34:13 INFO - 'blob_upload_branch': 'Fx-Team', 05:34:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:34:13 INFO - 'branch': 'Fx-Team', 05:34:13 INFO - 'buildbot_json_path': 'buildprops.json', 05:34:13 INFO - 'config_files': ('talos/mac_config.py',), 05:34:13 INFO - 'default_actions': ('clobber', 05:34:13 INFO - 'read-buildbot-config', 05:34:13 INFO - 'download-and-extract', 05:34:13 INFO - 'populate-webroot', 05:34:13 INFO - 'create-virtualenv', 05:34:13 INFO - 'install', 05:34:13 INFO - 'run-tests'), 05:34:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:34:13 INFO - 'download_minidump_stackwalk': True, 05:34:13 INFO - 'download_symbols': 'ondemand', 05:34:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:34:13 INFO - 'tooltool.py': '/tools/tooltool.py', 05:34:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:34:13 INFO - '/tools/misc-python/virtualenv.py')}, 05:34:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:34:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:34:13 INFO - 'installer_path': 'installer.exe', 05:34:13 INFO - 'log_level': 'info', 05:34:13 INFO - 'log_name': 'talos', 05:34:13 INFO - 'log_to_console': True, 05:34:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:34:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:34:13 INFO - 'opt_config_files': (), 05:34:13 INFO - 'pip_index': False, 05:34:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:13 INFO - 'cmd': ('bash', 05:34:13 INFO - '-c', 05:34:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:13 INFO - 'enabled': True, 05:34:13 INFO - 'halt_on_failure': False, 05:34:13 INFO - 'name': 'check_screen_resolution'},), 05:34:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:34:13 INFO - 'cmd': ('bash', 05:34:13 INFO - '-c', 05:34:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:34:13 INFO - 'enabled': True, 05:34:13 INFO - 'halt_on_failure': False, 05:34:13 INFO - 'name': 'check_screen_resolution'},), 05:34:13 INFO - 'run_cmd_checks_enabled': True, 05:34:13 INFO - 'sps_profile': False, 05:34:13 INFO - 'sps_profile_interval': 0, 05:34:13 INFO - 'suite': 'dromaeojs', 05:34:13 INFO - 'system_bits': '32', 05:34:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:34:13 INFO - 'title': 't-yosemite-r7-0316', 05:34:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:34:13 INFO - 'use_talos_json': True, 05:34:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:34:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:34:13 INFO - 'work_dir': 'build'} 05:34:13 INFO - ##### 05:34:13 INFO - ##### Running clobber step. 05:34:13 INFO - ##### 05:34:13 INFO - Running pre-action listener: _resource_record_pre_action 05:34:13 INFO - Running main action method: clobber 05:34:13 INFO - rmtree: /builds/slave/test/build 05:34:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:34:15 INFO - Running post-action listener: _resource_record_post_action 05:34:15 INFO - ##### 05:34:15 INFO - ##### Finished clobber step (success) 05:34:15 INFO - ##### 05:34:15 INFO - ##### 05:34:15 INFO - ##### Running read-buildbot-config step. 05:34:15 INFO - ##### 05:34:15 INFO - Running pre-action listener: _resource_record_pre_action 05:34:15 INFO - Running main action method: read_buildbot_config 05:34:15 INFO - Using buildbot properties: 05:34:15 INFO - { 05:34:15 INFO - "project": "", 05:34:15 INFO - "product": "firefox", 05:34:15 INFO - "script_repo_revision": "production", 05:34:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:34:15 INFO - "repository": "", 05:34:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:34:15 INFO - "buildid": "20160530032935", 05:34:15 INFO - "pgo_build": "False", 05:34:15 INFO - "basedir": "/builds/slave/test", 05:34:15 INFO - "buildnumber": 38, 05:34:15 INFO - "slavename": "t-yosemite-r7-0316", 05:34:15 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:34:15 INFO - "platform": "yosemite_r7", 05:34:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:34:15 INFO - "branch": "fx-team", 05:34:15 INFO - "repo_path": "integration/fx-team", 05:34:15 INFO - "revision": "f03b60a446f193327a21e0e2ca9892538b44b2f9", 05:34:15 INFO - "stage_platform": "macosx64", 05:34:15 INFO - "builduid": "18982da13ac846d09057dbf62e0f9603", 05:34:15 INFO - "slavebuilddir": "test" 05:34:15 INFO - } 05:34:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:34:15 INFO - Running post-action listener: _resource_record_post_action 05:34:15 INFO - ##### 05:34:15 INFO - ##### Finished read-buildbot-config step (success) 05:34:15 INFO - ##### 05:34:15 INFO - ##### 05:34:15 INFO - ##### Running download-and-extract step. 05:34:15 INFO - ##### 05:34:15 INFO - Running pre-action listener: _resource_record_pre_action 05:34:15 INFO - Running main action method: download_and_extract 05:34:15 INFO - mkdir: /builds/slave/test/build/tests 05:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:15 INFO - https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:34:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:34:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:34:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:35 INFO - Downloaded 1199 bytes. 05:34:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:34:35 INFO - Using the following test package requirements: 05:34:35 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:34:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:34:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:34:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'jsshell-mac.zip'], 05:34:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:34:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:34:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:34:35 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:34:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:34:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:34:35 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:34:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:34:35 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')]} 05:34:35 INFO - https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:34:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:41 INFO - Downloaded 18684756 bytes. 05:34:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:34:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:34:44 INFO - Return code: 0 05:34:44 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:34: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')]} 05:34:44 INFO - https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:34:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:34:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:47 INFO - Downloaded 18684756 bytes. 05:34:47 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'] 05:34:47 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 05:34:50 INFO - Return code: 0 05:34:50 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')]} 05:34:50 INFO - https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:34:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:34:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:34:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:51 INFO - Downloaded 10834034 bytes. 05:34:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:34:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:34:52 INFO - Return code: 0 05:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:34:52 INFO - https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:34:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:34:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:34:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:34:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7JYItwNS5qAuRq7yf9caQ/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 05:34:59 INFO - Downloaded 110491590 bytes. 05:34:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:34:59 INFO - mkdir: /builds/slave/test/properties 05:34:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:34:59 INFO - Writing to file /builds/slave/test/properties/build_url 05:34:59 INFO - Contents: 05:34:59 INFO - build_url:https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:35:01 INFO - Running post-action listener: _resource_record_post_action 05:35:01 INFO - Running post-action listener: set_extra_try_arguments 05:35:01 INFO - ##### 05:35:01 INFO - ##### Finished download-and-extract step (success) 05:35:01 INFO - ##### 05:35:01 INFO - ##### 05:35:01 INFO - ##### Running populate-webroot step. 05:35:01 INFO - ##### 05:35:01 INFO - Running pre-action listener: _resource_record_pre_action 05:35:01 INFO - Running main action method: populate_webroot 05:35:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:35:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:35:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:35:01 INFO - '--tppagecycles', 05:35:01 INFO - '7'], 05:35:01 INFO - 'tests': ['tsvgm']}}, 05:35:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:35:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:35:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:35:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:35:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:35:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['damp', 'tps']}, 05:35:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'tests': ['damp', 'tps']}, 05:35:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['dromaeo_dom']}, 05:35:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:35:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['a11yr', 05:35:01 INFO - 'ts_paint', 05:35:01 INFO - 'tpaint', 05:35:01 INFO - 'sessionrestore', 05:35:01 INFO - 'sessionrestore_no_auto_restore', 05:35:01 INFO - 'tabpaint']}, 05:35:01 INFO - 'other-e10s': {'tests': ['a11yr', 05:35:01 INFO - 'ts_paint', 05:35:01 INFO - 'tpaint', 05:35:01 INFO - 'sessionrestore', 05:35:01 INFO - 'sessionrestore_no_auto_restore', 05:35:01 INFO - 'tabpaint']}, 05:35:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:35:01 INFO - 'ts_paint', 05:35:01 INFO - 'tpaint', 05:35:01 INFO - 'sessionrestore', 05:35:01 INFO - 'sessionrestore_no_auto_restore', 05:35:01 INFO - 'tabpaint']}, 05:35:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:35:01 INFO - 'ts_paint', 05:35:01 INFO - 'tpaint', 05:35:01 INFO - 'sessionrestore', 05:35:01 INFO - 'sessionrestore_no_auto_restore', 05:35:01 INFO - 'tabpaint']}, 05:35:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['a11yr', 05:35:01 INFO - 'ts_paint', 05:35:01 INFO - 'tpaint', 05:35:01 INFO - 'sessionrestore', 05:35:01 INFO - 'sessionrestore_no_auto_restore', 05:35:01 INFO - 'tabpaint']}, 05:35:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['a11yr', 05:35:01 INFO - 'ts_paint', 05:35:01 INFO - 'tpaint', 05:35:01 INFO - 'sessionrestore', 05:35:01 INFO - 'sessionrestore_no_auto_restore', 05:35:01 INFO - 'tabpaint']}, 05:35:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['tsvgx', 05:35:01 INFO - 'tsvgr_opacity', 05:35:01 INFO - 'tart', 05:35:01 INFO - 'tscrollx', 05:35:01 INFO - 'cart']}, 05:35:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:35:01 INFO - 'tsvgr_opacity', 05:35:01 INFO - 'tart', 05:35:01 INFO - 'tscrollx', 05:35:01 INFO - 'cart']}, 05:35:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'talos_options': ['--disable-e10s'], 05:35:01 INFO - 'tests': ['tp5o']}, 05:35:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'tests': ['tp5o']}, 05:35:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'talos_options': ['--disable-e10s', 05:35:01 INFO - '--xperf_path', 05:35:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:01 INFO - 'tests': ['tp5n']}, 05:35:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:35:01 INFO - 'talos_options': ['--xperf_path', 05:35:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:35:01 INFO - 'tests': ['tp5n']}}, 05:35:01 INFO - 'talos.zip': {'path': '', 05:35:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:35:01 INFO - Running post-action listener: _resource_record_post_action 05:35:01 INFO - ##### 05:35:01 INFO - ##### Finished populate-webroot step (success) 05:35:01 INFO - ##### 05:35:01 INFO - ##### 05:35:01 INFO - ##### Running create-virtualenv step. 05:35:01 INFO - ##### 05:35:01 INFO - Running pre-action listener: _resource_record_pre_action 05:35:01 INFO - Running main action method: create_virtualenv 05:35:01 INFO - Creating virtualenv /builds/slave/test/build/venv 05:35:01 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 05:35:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:35:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:35:01 INFO - Using real prefix '/tools/python27' 05:35:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:35:02 INFO - Installing distribute.............................................................................................................................................................................................done. 05:35:05 INFO - Installing pip.................done. 05:35:05 INFO - Return code: 0 05:35:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:05 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:05 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 05:35:05 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 05:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:05 INFO - 'HOME': '/Users/cltbld', 05:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:05 INFO - 'LOGNAME': 'cltbld', 05:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:05 INFO - 'MOZ_NO_REMOTE': '1', 05:35:05 INFO - 'NO_EM_RESTART': '1', 05:35:05 INFO - 'PAGER': '/bin/cat', 05:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:05 INFO - 'PWD': '/builds/slave/test', 05:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:05 INFO - 'SHELL': '/bin/bash', 05:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:05 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:05 INFO - 'USER': 'cltbld', 05:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:05 INFO - 'XPC_FLAGS': '0x0', 05:35:05 INFO - 'XPC_SERVICE_NAME': '0', 05:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:05 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 05:35:05 INFO - Cleaning up... 05:35:05 INFO - Return code: 0 05:35:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:05 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:05 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 05:35:05 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 05:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:05 INFO - 'HOME': '/Users/cltbld', 05:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:05 INFO - 'LOGNAME': 'cltbld', 05:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:05 INFO - 'MOZ_NO_REMOTE': '1', 05:35:05 INFO - 'NO_EM_RESTART': '1', 05:35:05 INFO - 'PAGER': '/bin/cat', 05:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:05 INFO - 'PWD': '/builds/slave/test', 05:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:05 INFO - 'SHELL': '/bin/bash', 05:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:05 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:05 INFO - 'USER': 'cltbld', 05:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:05 INFO - 'XPC_FLAGS': '0x0', 05:35:05 INFO - 'XPC_SERVICE_NAME': '0', 05:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:06 INFO - Downloading/unpacking psutil>=3.1.1 05:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:35:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:09 INFO - Installing collected packages: psutil 05:35:09 INFO - Running setup.py install for psutil 05:35:09 INFO - building 'psutil._psutil_osx' extension 05:35:09 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 05:35:10 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 05:35:10 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 05:35:10 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 05:35:10 INFO - building 'psutil._psutil_posix' extension 05:35:10 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 05:35:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:35:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:35:10 INFO - ^ 05:35:10 INFO - 1 warning generated. 05:35:10 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 05:35:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:35:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:35:10 INFO - Successfully installed psutil 05:35:10 INFO - Cleaning up... 05:35:10 INFO - Return code: 0 05:35:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:10 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:10 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 05:35:10 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 05:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:10 INFO - 'HOME': '/Users/cltbld', 05:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:10 INFO - 'LOGNAME': 'cltbld', 05:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:10 INFO - 'MOZ_NO_REMOTE': '1', 05:35:10 INFO - 'NO_EM_RESTART': '1', 05:35:10 INFO - 'PAGER': '/bin/cat', 05:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:10 INFO - 'PWD': '/builds/slave/test', 05:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:10 INFO - 'SHELL': '/bin/bash', 05:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:10 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:10 INFO - 'USER': 'cltbld', 05:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:10 INFO - 'XPC_FLAGS': '0x0', 05:35:10 INFO - 'XPC_SERVICE_NAME': '0', 05:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:35:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:35:13 INFO - Installing collected packages: mozsystemmonitor 05:35:13 INFO - Running setup.py install for mozsystemmonitor 05:35:13 INFO - Successfully installed mozsystemmonitor 05:35:13 INFO - Cleaning up... 05:35:13 INFO - Return code: 0 05:35:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:35: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')]} 05:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35: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')]} 05:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:13 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:13 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 05:35:13 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 05:35:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:13 INFO - 'HOME': '/Users/cltbld', 05:35:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:13 INFO - 'LOGNAME': 'cltbld', 05:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:13 INFO - 'MOZ_NO_REMOTE': '1', 05:35:13 INFO - 'NO_EM_RESTART': '1', 05:35:13 INFO - 'PAGER': '/bin/cat', 05:35:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:13 INFO - 'PWD': '/builds/slave/test', 05:35:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:13 INFO - 'SHELL': '/bin/bash', 05:35:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:13 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:13 INFO - 'USER': 'cltbld', 05:35:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:13 INFO - 'XPC_FLAGS': '0x0', 05:35:13 INFO - 'XPC_SERVICE_NAME': '0', 05:35:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:13 INFO - Downloading/unpacking blobuploader==1.2.4 05:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:16 INFO - Downloading blobuploader-1.2.4.tar.gz 05:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:35:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:35:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:17 INFO - Downloading docopt-0.6.1.tar.gz 05:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:35:18 INFO - Installing collected packages: blobuploader, requests, docopt 05:35:18 INFO - Running setup.py install for blobuploader 05:35:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:35:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:35:18 INFO - Running setup.py install for requests 05:35:18 INFO - Running setup.py install for docopt 05:35:18 INFO - Successfully installed blobuploader requests docopt 05:35:18 INFO - Cleaning up... 05:35:18 INFO - Return code: 0 05:35:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:35:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:19 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:19 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 05:35:19 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 05:35:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:19 INFO - 'HOME': '/Users/cltbld', 05:35:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:19 INFO - 'LOGNAME': 'cltbld', 05:35:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:19 INFO - 'MOZ_NO_REMOTE': '1', 05:35:19 INFO - 'NO_EM_RESTART': '1', 05:35:19 INFO - 'PAGER': '/bin/cat', 05:35:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:19 INFO - 'PWD': '/builds/slave/test', 05:35:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:19 INFO - 'SHELL': '/bin/bash', 05:35:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:19 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:19 INFO - 'USER': 'cltbld', 05:35:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:19 INFO - 'XPC_FLAGS': '0x0', 05:35:19 INFO - 'XPC_SERVICE_NAME': '0', 05:35:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:19 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-FQf4n6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:19 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-ikCgXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:19 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-y5kVG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-oRWKyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-5RRQtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-a0Jlgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-HopEwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-jXkrrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-FiLb_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-wZgXIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-Zdc6ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-id8w5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:20 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-iGvCLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:21 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-jNtFoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:21 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-Z9ltnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:21 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-qwMI5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:21 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-c7TNr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:35:21 INFO - Running setup.py install for manifestparser 05:35:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:35:21 INFO - Running setup.py install for mozcrash 05:35:21 INFO - Running setup.py install for mozdebug 05:35:21 INFO - Running setup.py install for mozdevice 05:35:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Running setup.py install for mozfile 05:35:22 INFO - Running setup.py install for mozhttpd 05:35:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Running setup.py install for mozinfo 05:35:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Running setup.py install for mozInstall 05:35:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:35:22 INFO - Running setup.py install for mozleak 05:35:22 INFO - Running setup.py install for mozlog 05:35:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:35:23 INFO - Running setup.py install for moznetwork 05:35:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:35:23 INFO - Running setup.py install for mozprocess 05:35:23 INFO - Running setup.py install for mozprofile 05:35:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:35:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:35:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:35:23 INFO - Running setup.py install for mozrunner 05:35:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:35:23 INFO - Running setup.py install for mozscreenshot 05:35:23 INFO - Running setup.py install for moztest 05:35:23 INFO - Running setup.py install for mozversion 05:35:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:35:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:35:24 INFO - Cleaning up... 05:35:24 INFO - Return code: 0 05:35:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35: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')]} 05:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35: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')]} 05:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:24 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:24 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 05:35:24 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 05:35:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:24 INFO - 'HOME': '/Users/cltbld', 05:35:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:24 INFO - 'LOGNAME': 'cltbld', 05:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:24 INFO - 'MOZ_NO_REMOTE': '1', 05:35:24 INFO - 'NO_EM_RESTART': '1', 05:35:24 INFO - 'PAGER': '/bin/cat', 05:35:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:24 INFO - 'PWD': '/builds/slave/test', 05:35:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:24 INFO - 'SHELL': '/bin/bash', 05:35:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:24 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:24 INFO - 'USER': 'cltbld', 05:35:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:24 INFO - 'XPC_FLAGS': '0x0', 05:35:24 INFO - 'XPC_SERVICE_NAME': '0', 05:35:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:35:24 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-qXfApd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:35:24 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)) 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:35:24 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-2Ju0fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:35:24 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)) 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:35:24 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-6I_qtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:35:24 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)) 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:35:24 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-PMQTvx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:35:24 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)) 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:35:24 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-maJbvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:35:24 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)) 05:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:35:24 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-iL_zkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-A7DLuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-vXhMqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-meHblK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-RnrM_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-A6TrxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-PChJCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-GAMlMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-3OxGh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:35:25 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)) 05:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:25 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-k5lLfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:35:26 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)) 05:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:35:26 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-8HGvwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:35:26 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)) 05:35:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:35:26 INFO - Running setup.py (path:/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/pip-3ZmOu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:35:26 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)) 05:35:26 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)) 05:35:26 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)) 05:35:26 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)) 05:35:26 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)) 05:35:26 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)) 05:35:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:28 INFO - Downloading blessings-1.6.tar.gz 05:35:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:35:28 INFO - Installing collected packages: blessings 05:35:28 INFO - Running setup.py install for blessings 05:35:28 INFO - Successfully installed blessings 05:35:28 INFO - Cleaning up... 05:35:29 INFO - Return code: 0 05:35:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:35:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:29 INFO - Reading from file tmpfile_stdout 05:35:29 INFO - Current package versions: 05:35:29 INFO - blessings == 1.6 05:35:29 INFO - blobuploader == 1.2.4 05:35:29 INFO - docopt == 0.6.1 05:35:29 INFO - manifestparser == 1.1 05:35:29 INFO - mozInstall == 1.12 05:35:29 INFO - mozcrash == 0.17 05:35:29 INFO - mozdebug == 0.1 05:35:29 INFO - mozdevice == 0.48 05:35:29 INFO - mozfile == 1.2 05:35:29 INFO - mozhttpd == 0.7 05:35:29 INFO - mozinfo == 0.9 05:35:29 INFO - mozleak == 0.1 05:35:29 INFO - mozlog == 3.2 05:35:29 INFO - moznetwork == 0.27 05:35:29 INFO - mozprocess == 0.22 05:35:29 INFO - mozprofile == 0.28 05:35:29 INFO - mozrunner == 6.11 05:35:29 INFO - mozscreenshot == 0.1 05:35:29 INFO - mozsystemmonitor == 0.3 05:35:29 INFO - moztest == 0.7 05:35:29 INFO - mozversion == 1.4 05:35:29 INFO - psutil == 3.1.1 05:35:29 INFO - requests == 1.2.3 05:35:29 INFO - wsgiref == 0.1.2 05:35:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:35:29 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')]} 05:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:29 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')]} 05:35:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:29 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:29 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 05:35:29 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 05:35:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:29 INFO - 'HOME': '/Users/cltbld', 05:35:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:29 INFO - 'LOGNAME': 'cltbld', 05:35:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:29 INFO - 'MOZ_NO_REMOTE': '1', 05:35:29 INFO - 'NO_EM_RESTART': '1', 05:35:29 INFO - 'PAGER': '/bin/cat', 05:35:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:29 INFO - 'PWD': '/builds/slave/test', 05:35:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:29 INFO - 'SHELL': '/bin/bash', 05:35:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:29 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:29 INFO - 'USER': 'cltbld', 05:35:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:29 INFO - 'XPC_FLAGS': '0x0', 05:35:29 INFO - 'XPC_SERVICE_NAME': '0', 05:35:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 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)) 05:35:29 INFO - Cleaning up... 05:35:29 INFO - Return code: 0 05:35:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:35:29 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')]} 05:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:29 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')]} 05:35:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:29 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:29 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 05:35:29 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 05:35:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:29 INFO - 'HOME': '/Users/cltbld', 05:35:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:29 INFO - 'LOGNAME': 'cltbld', 05:35:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:29 INFO - 'MOZ_NO_REMOTE': '1', 05:35:29 INFO - 'NO_EM_RESTART': '1', 05:35:29 INFO - 'PAGER': '/bin/cat', 05:35:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:29 INFO - 'PWD': '/builds/slave/test', 05:35:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:29 INFO - 'SHELL': '/bin/bash', 05:35:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:29 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:29 INFO - 'USER': 'cltbld', 05:35:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:29 INFO - 'XPC_FLAGS': '0x0', 05:35:29 INFO - 'XPC_SERVICE_NAME': '0', 05:35:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:29 INFO - Downloading/unpacking jsonschema 05:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:35:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:35:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:35:32 INFO - Downloading/unpacking functools32 (from jsonschema) 05:35: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 05:35: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 05:35: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 05:35: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 05:35: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 05:35: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 05:35:32 INFO - Downloading functools32-3.2.3-2.tar.gz 05:35:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:35:32 INFO - warning: no files found matching '*.txt' 05:35:32 INFO - no previously-included directories found matching 'build' 05:35:32 INFO - no previously-included directories found matching 'dist' 05:35:32 INFO - no previously-included directories found matching '.git*' 05:35:32 INFO - Installing collected packages: jsonschema, functools32 05:35:32 INFO - Running setup.py install for functools32 05:35:32 INFO - warning: no files found matching '*.txt' 05:35:32 INFO - no previously-included directories found matching 'build' 05:35:32 INFO - no previously-included directories found matching 'dist' 05:35:32 INFO - no previously-included directories found matching '.git*' 05:35:32 INFO - Successfully installed jsonschema functools32 05:35:32 INFO - Cleaning up... 05:35:33 INFO - Return code: 0 05:35:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:35:33 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')]} 05:35:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:35:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:33 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')]} 05:35:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:35:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:35:33 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 0x107be8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bee570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe8473e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, '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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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 05:35:33 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 05:35:33 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 05:35:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:35:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:35:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:35:33 INFO - 'HOME': '/Users/cltbld', 05:35:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:35:33 INFO - 'LOGNAME': 'cltbld', 05:35:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:35:33 INFO - 'MOZ_NO_REMOTE': '1', 05:35:33 INFO - 'NO_EM_RESTART': '1', 05:35:33 INFO - 'PAGER': '/bin/cat', 05:35:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:35:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:35:33 INFO - 'PWD': '/builds/slave/test', 05:35:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:35:33 INFO - 'SHELL': '/bin/bash', 05:35:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:35:33 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:35:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:35:33 INFO - 'USER': 'cltbld', 05:35:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:35:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:35:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:35:33 INFO - 'XPC_FLAGS': '0x0', 05:35:33 INFO - 'XPC_SERVICE_NAME': '0', 05:35:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:35:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:35:33 INFO - Downloading/unpacking flake8 05:35:33 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 05:35:33 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 05:35:33 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 05:35:33 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 05:35:33 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 05:35:33 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 05:35:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:35:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:35:35 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 05:35:35 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 05:35:35 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 05:35:35 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 05:35:35 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 05:35:35 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 05:35:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:35:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:35:36 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 05:35:36 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 05:35:36 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 05:35:36 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 05:35:36 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 05:35:36 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 05:35:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:35:36 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 05:35:36 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 05:35:36 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 05:35:36 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 05:35:36 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 05:35:36 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 05:35:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:35:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:35:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:35:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:35:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:35:37 INFO - no previously-included directories found matching 'docs/_build' 05:35:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:35:37 INFO - Running setup.py install for pep8 05:35:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:35:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:35:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:35:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:35:37 INFO - no previously-included directories found matching 'docs/_build' 05:35:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:35:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:35:37 INFO - Cleaning up... 05:35:37 INFO - Return code: 0 05:35:37 INFO - Running post-action listener: _resource_record_post_action 05:35:37 INFO - Running post-action listener: _start_resource_monitoring 05:35:37 INFO - Starting resource monitoring. 05:35:37 INFO - ##### 05:35:37 INFO - ##### Finished create-virtualenv step (success) 05:35:37 INFO - ##### 05:35:37 INFO - ##### 05:35:37 INFO - ##### Running install step. 05:35:37 INFO - ##### 05:35:37 INFO - Running pre-action listener: _resource_record_pre_action 05:35:37 INFO - Running main action method: install 05:35:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:35:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:35:37 INFO - Reading from file tmpfile_stdout 05:35:37 INFO - Detecting whether we're running mozinstall >=1.0... 05:35:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:35:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:35:37 INFO - Reading from file tmpfile_stdout 05:35:37 INFO - Output received: 05:35:37 INFO - Usage: mozinstall [options] installer 05:35:37 INFO - Options: 05:35:37 INFO - -h, --help show this help message and exit 05:35:37 INFO - -d DEST, --destination=DEST 05:35:37 INFO - Directory to install application into. [default: 05:35:37 INFO - "/builds/slave/test"] 05:35:37 INFO - --app=APP Application being installed. [default: firefox] 05:35:37 INFO - mkdir: /builds/slave/test/build/application 05:35:37 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'] 05:35:37 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 05:36:11 INFO - Reading from file tmpfile_stdout 05:36:11 INFO - Output received: 05:36:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:36:11 INFO - Running post-action listener: _resource_record_post_action 05:36:11 INFO - ##### 05:36:11 INFO - ##### Finished install step (success) 05:36:11 INFO - ##### 05:36:11 INFO - ##### 05:36:11 INFO - ##### Running run-tests step. 05:36:11 INFO - ##### 05:36:11 INFO - Running pre-action listener: _resource_record_pre_action 05:36:11 INFO - Running main action method: run_tests 05:36:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:36:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:36:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:36:11 INFO - 2016-05-30 05:36:11.807 screenresolution[1644:8403] starting screenresolution argv=screenresolution get 05:36:11 INFO - 2016-05-30 05:36:11.810 screenresolution[1644:8403] Display 0: 1600x1200x32@60 05:36:11 INFO - 2016-05-30 05:36:11.815 screenresolution[1645:8405] starting screenresolution argv=screenresolution list 05:36:11 INFO - Available Modes on Display 0 05:36:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:36:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:36:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:36:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:36:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:36:12 INFO - Intel Iris: 05:36:12 INFO - Chipset Model: Intel Iris 05:36:12 INFO - Type: GPU 05:36:12 INFO - Bus: Built-In 05:36:12 INFO - VRAM (Dynamic, Max): 1536 MB 05:36:12 INFO - Vendor: Intel (0x8086) 05:36:12 INFO - Device ID: 0x0a2e 05:36:12 INFO - Revision ID: 0x0009 05:36:12 INFO - Displays: 05:36:12 INFO - Display: 05:36:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:36:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:36:12 INFO - Display Serial Number: 1600x1200 60 05:36:12 INFO - Main Display: Yes 05:36:12 INFO - Mirror: Off 05:36:12 INFO - Online: Yes 05:36:12 INFO - Rotation: Supported 05:36:12 INFO - Return code: 0 05:36:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:36:12 INFO - Python 2.7.3 05:36:12 INFO - Return code: 0 05:36:12 INFO - grabbing minidump binary from tooltool 05:36: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')]} 05:36:12 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 0x107a8cd30>, '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 0x1078b0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107a83b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:36:12 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 05:36:12 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 05:36:12 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 05:36:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:36:12 INFO - Return code: 0 05:36:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:36:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:36:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:36:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:36:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:36:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:36:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:36:13 INFO - Return code: 0 05:36:13 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/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 05:36:13 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/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0316 --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 05:36:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GJoAm6r8jZ/Render', 05:36:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:36:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:36:13 INFO - 'HOME': '/Users/cltbld', 05:36:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:36:13 INFO - 'LOGNAME': 'cltbld', 05:36:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:36:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:36:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:36:13 INFO - 'MOZ_NO_REMOTE': '1', 05:36:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:36:13 INFO - 'NO_EM_RESTART': '1', 05:36:13 INFO - 'PAGER': '/bin/cat', 05:36:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:36:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:36:13 INFO - 'PWD': '/builds/slave/test', 05:36:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:36:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:36:13 INFO - 'SHELL': '/bin/bash', 05:36:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lFEOjgwzfj/Listeners', 05:36:13 INFO - 'TMPDIR': '/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/', 05:36:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:36:13 INFO - 'USER': 'cltbld', 05:36:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:36:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:36:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:36:13 INFO - 'XPC_FLAGS': '0x0', 05:36:13 INFO - 'XPC_SERVICE_NAME': '0', 05:36:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:36:13 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/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 05:36:13 INFO - mozversion application_buildid: 20160530032935 05:36:13 INFO - mozversion application_changeset: f03b60a446f193327a21e0e2ca9892538b44b2f9 05:36:13 INFO - mozversion application_display_name: Nightly 05:36:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:36:13 INFO - mozversion application_name: Firefox 05:36:13 INFO - mozversion application_remotingname: firefox 05:36:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:36:13 INFO - mozversion application_vendor: Mozilla 05:36:13 INFO - mozversion application_version: 49.0a1 05:36:13 INFO - mozversion platform_buildid: 20160530032935 05:36:13 INFO - mozversion platform_changeset: f03b60a446f193327a21e0e2ca9892538b44b2f9 05:36:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:36:13 INFO - mozversion platform_version: 49.0a1 05:36:13 INFO - using testdate: 1464611773 05:36:13 INFO - actual date: 1464611773 05:36:13 INFO - starting webserver on 'localhost:49277' 05:36:13 INFO - SUITE-START | Running 2 tests 05:36:13 INFO - TEST-START | dromaeo_css 05:36:13 INFO - operating with platform_type : mac_ 05:36:13 INFO - Initialising browser for dromaeo_css test... 05:36:13 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/tmpMkh71Y/profile http://localhost:49277/getInfo.html) 05:36:15 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 05:36:15 INFO - PROCESS | 1663 | colorDepth:24 05:36:15 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 05:36:15 INFO - PROCESS | 1663 | __metrics 05:36:15 INFO - PROCESS | 1663 | 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" 05:36:15 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:36:16 INFO - TEST-INFO | 1663: exit 0 05:36:16 INFO - Browser initialized. 05:36:16 INFO - Running cycle 1/1 for dromaeo_css test... 05:36:16 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/tmpMkh71Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:36:28 INFO - PROCESS | 1666 | RSS: Main: 241586176 05:36:28 INFO - PROCESS | 1666 | 05:37:20 INFO - PROCESS | 1666 | 1464611840136 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] 05:39:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 05:39:31 INFO - PROCESS | 1666 | RSS: Main: 343322624 05:39:31 INFO - PROCESS | 1666 | 05:42:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 05:42:35 INFO - PROCESS | 1666 | RSS: Main: 375631872 05:42:35 INFO - PROCESS | 1666 | 05:45:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 05:45:37 INFO - PROCESS | 1666 | RSS: Main: 368959488 05:45:37 INFO - PROCESS | 1666 | 05:48:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 05:48:40 INFO - PROCESS | 1666 | RSS: Main: 371212288 05:48:40 INFO - PROCESS | 1666 | 05:51:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 05:51:44 INFO - PROCESS | 1666 | RSS: Main: 342896640 05:51:44 INFO - PROCESS | 1666 | 05:54:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 05:54:48 INFO - PROCESS | 1666 | RSS: Main: 391962624 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | __start_tp_report 05:54:48 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:54:48 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:54:48 INFO - PROCESS | 1666 | |i|pagename|runs| 05:54:48 INFO - PROCESS | 1666 | |0;dojo.html;2860.7711383089354;3242.832580271102;3278.7868788528745;3428.7942723436613;3455.602605700345;5535.142052981794;5561.33264008319;5635.718214089289;5640.605157638967;5665.29183852018;5655.717214139287;5690.5447564194865;5799.159121927318;5842.941570584293;5843.737031833567;5104.617153713468;5133.3326667533165;5152.072626927152;5179.300794392747;5267.65760225586;5257.474252574751;5282.656627319212;5287.444818294081;5298.576113910897;5313.867153321178;5077.619178561604;5095.898082038376;5098.719570423626;5118.232265160255;5156.458571849939;4668.206404911173;5096.51583099605;5162.380514338266;5271.815486457956;5275.472452754732;5075.289459475676;5115.488451154854;5147.202183661539;5156.226566015128;5187.714675692835;5184.937087897009;5516.475934786189;5568.554515638739;5615.466530679579;5621.971890140564;5232.2413250078525;6063.130336476962;6106.633601983851;6159.938400615982;6163.568550201488;6142.692865356759;6149.969250153766;6180.258368995705;6184.474319682832;6217.906731399033;5827.825165245049;5834.2998840139035;5842.065269556851;5843;5863.091220860688;6097.494431795059;6144.15675297409;6185.628862268279;6191.318954914956;6200.906986395207;5841.1238314253205;6144.126041557279;6150.261968563801;6161.630302181883;6178.814635560941;6173.290071641819;6174.6912654368;6230.470410015108;6252.3122456529745;6259.812205633838;5694.971525142348;6026.939730602726;6089.725962331706;6111.236095488064;6153.261608606996;6219.378062193835;6220.440160385587;6223.813285601439;6231.065340198916;6237.4698150656795;4928.433230178575;4928.901421971612;4929.260610908393;4934.753262336941;4955.677880937727;4376.343548467756;4390.978045109754;4400.075984043323;4451.3545535897765;4481.439820022497;5628.887422251532;5629.127485239795;5632.183333416713;5632.746526406321;5695.288088988877;6233.501319894398;6244.224092714221;6251.468625166821;6269.247690277196;6290.119383286344;5521.641093328921;5567.860803479945;5584.86037849057;5617;5638.126090455986;5364.222187782828;5366.060939335679;5367.329083864517;5371.838844834661;5378.892422151614;6293.087502312139;6322.367763223733;6333.714982825876;6341.4292713656;6350.396712312323;6098.704546243851;6107.725152368243;6108.311716708011;6139.969300153471;6157.692115394302;6349;6352.110704501466;6364.904526432199;6369.172007638977;6374.521910856612;4981.676191047571;6073.392660734068;6091.208142941389;6094.481969032682;6097.908531371944;5680.630759000652;6099.298580663103;6148.508119350352;6161.260648722182;6207.844803879759;6082.7262773174425;6083.908741368971;6094.54290928191;6098.512119030378;6123.203983482297;6024;6056;6088.512918966383;6100.359462256577;6104.084387341934;5732.656040637575;5829.76680932758;5857.092150716646;5893.381194974466;5894.498967587631;6043.12374705674;6051.80175713469;6056.905726312452;6074.514038876968;6133.969330153321;4897.657163998486;5741.397153299011;5904.9704751475965;5918.7928422505;5927.199828023162 05:54:48 INFO - PROCESS | 1666 | |1;ext.html;510.50481033397614;517.7799435240022;519.633658270919;528.711852040638;550.2489102375259;29960;41854.95362615936;43185.704428867146;43259;43306.56693433065;44250;44508.77745611275;44533.77733111338;44955.550444495544;44964;8556.101609331025;9488.95255523723;9709.660161894335;9723.854142187873;9729.221662267037;8679.566021698922;11563.306201627887;11746.588869389572;11772.705682357924;11953.40232988347;11710.53157873684;11785.057195424346;11787.11596630252;11788;11796.938275555198;8620.698275560388;11554.075673946065;11687.5909343173;11755.235909665844;11782;11325.150613704005;11424.200305978586;11424.42877856104;11426.94286528562;11442.970132688099;40519.59480405188;40808.591914080775;40888.977775555555;40914.9771255718;40939.59060409388;3275.132089996153;3587;3944.3886197639413;3987.48162738845;4063.7155399122075;31168.376632467225;32047.839760801045;32493.862714805105;32517.349653006808;32639;3295.143262751678;3424.434968230239;3428.0230134411822;3459.515667806509;3461.3250416168858;479127;479398;479810;480094;481951;54366;61638.691806541574;62114;62184.75630487455;62296;58299;65813.34186658169;65946.67026664836;65957.01064484037;66652;16848.062472815538;17030.57423564421;17057.573560660836;17092.350685643745;17100.144992750313;11740.471678774305;12076.973457256232;12850.871491285154;13135.802962955564;13197;17415.303387864344;17453.65092698164;17455.912720436318;17480.863743856607;17511.64976700459;7435.839768098536;7473.140588832245;7502.324790768748;7505.399568034544;7511.098668159856;941.8304705153138;1051.2588625019343;1107.894749998749;1113.1706878375662;1118.1446193661855;29129.56305655417;30254.84872575623;30334.393312134074;30357.54463683002;31063.71916774154;1121.8653761548503;1155.8324043013715;1156.7455159864985;1163.2845799833042;1207.4144040140525;14985.700285994219;15432.845671543366;15694.372225110868;15711.921440392725;15811;632177;633287;636240;637342;638571;60355.69822150949;60439;60441.69779151076;60517.0922436155;60637.393626063174;1316.1576590981779;1440.6038339456734;1450.8766754825747;1467.8752306054105;1498.1760031982149;24268.63597046081;30058.444477746667;31148.844255778575;32042;32062;51969.740151299;53076.73461632667;53203.733981329846;53234.4676553245;53241.20138198008;588946;602435;603831;604427;604683;44473.55526444694;44579.55420445754;45984;46249.53750462587;46358.53641463677;40402.191956160124;49113.772155696395;49179;50037.74981125071;50170.996580068924;38563.61436385712;40611.59388406078;40627.1090691854;40951.36210971514;41555.792221038704;38067;39718.80140599279;39994.800025999684;40119.1976160481;41057.384139237336;31828.840855795574;38628.80685596554;39407.80296098501;39457;39692.603073968894;33726.156846079044;34642.133946651535;35505.64494355127;35508.467372989944;35552.64447355493;21141.89429052845;23943.880280598485;25210.865511052412;25490.362740931625;26335.604965925908 05:54:48 INFO - PROCESS | 1666 | |2;jquery.html;379.68865530265185;385.33337326425277;390.77530420008486;402.9899252518685;411.7118017387829;15780.684386312267;17691;21639;21900.89049554752;21918.013689383977;20679;20939.848308343036;21815.781842181572;21915.780842191572;21969;5315.574754019669;5945.999999999989;5982.461578457938;6151.261848578177;6161.137440758297;6019.367966363535;6084.330723620398;6108;6111.052786818051;6142.96928515359;5863.355030946614;6018.5254612619965;6029.095635654643;6066.484348830332;6073.60521566099;5722.227499287585;6008.128821320905;6029.939700602981;6051.1225872248715;6095.6952152392205;5875.706214689291;5890.617109887844;5911.054231323011;5912.526997840162;5916.26046744157;13328.666783330398;19895.20419183231;23908.04367825285;25168.49663006748;25540.616890746653;2198.1976578548715;2215.29110684581;2292.3123063081007;2307.077169132344;2576.4718232762425;9664;9837.19605432798;10055.195584353234;10149.695509134737;10293.485325733684;1535.2707463954644;1717.4590004148654;1792.3010026089835;1816.7638207033;1825.6805059114602;92801.53599231961;102377;104956;120048.39975800065;126499.36750316282;11636.709082273012;11644.592439264585;11650.30098194111;11690.532378704927;11846.407679615984;14869.256537173098;15001.399944002336;15008.324625391884;15018.023828451116;15018.474353397578;8461.365397595082;8738.825223495622;8741.606627701642;8854.513001784964;8880.378373513791;8593.742187734379;8621.31029517637;8623.180797824196;8628.52543110135;8729.650813967497;4473.194824931544;4565.726056436624;4573.738497370444;4578.175928332932;4636.281376386665;1904.9238030478903;1945.1538580717433;1946.3479734289172;1988.5227545389291;1993.4119434766778;896.8699538566872;916.3814425262921;932.5896605493562;940.2618944128826;1012.8075665623508;20431.8978405107;21501.559750439854;21780.237691680715;21841.427155599602;21954.78045219559;953.0897992417225;969.4474149734583;971.7133445633555;999.3703966501058;1059.3273271472517;11032.50353734084;11336;11539.942300288612;11544.826827597593;11548.884511154949;533458;584756;593909.426340152;594679.0266048642;605531;96362;101891.98108018973;122160.38919805344;125716;139654;945.1021529546958;979.1090108001684;979.7942432089199;988.3476905242649;1068.3589846092282;18504.81495185031;19891.701624475932;19911.402657920287;19976.400707978784;19994.90002549978;37252.06869828276;47428.7628561855;47714.28428573505;48459.5154048455;49556.75221623869;75956;76316;76596.61701691679;77126.22873771415;77665.2233477658;7081.787546373617;7531.209223031503;7570.394368450621;7616.428767842323;7785.104712957856;12809.679758006125;13010.869891300967;13084.411201495765;13220.471181152647;13306.201627902357;9513.001134880738;10583.682489525327;11260.831087533857;11391.974722275036;11460.828087578526;11206;11259.268147570381;11277.661670149908;11518.53925842957;11537.711557211136;11294.58817647794;11359.602413915472;11457.59898403552;11768.411579420823;11801.114916381477;6697.196336439659;7524.699012039458;7548.698052078075;7575.393968482618;7739.264769846855;9925.404475731479;9942.850857237003;11432.885671143182;11606.361650109326;11688.70778230517 05:54:48 INFO - PROCESS | 1666 | |3;mootools.html;1424.238032652531;1550.914699691517;1590.2287390615552;1816.5277027972722;1960.8235258826712;7918.84162316754;8378.62296196671;8392.118827523098;8427.283680887123;8607.09625489324;8625.956870215656;8694.869576956335;8835.911640883589;8839.204471597555;8843.734687959372;2360.940976475589;2393.377721792338;2395.928122156329;2397.5564520563753;2398.7241467231315;2357.787799098081;2386.0575072846214;2400.627902675088;2402.6756387887585;2403.267003563912;2342.344143639775;2353.6587194856734;2364.137089962169;2378.286514045788;2380.059876348841;1979.2478858033999;2334.9883250583807;2354.6114891042957;2368.3723813189517;2374.643803429482;2316.652502124678;2345.7185137783404;2347.5657003454244;2355.140373763581;2355.8468699534646;8765.430247033986;8847.557622118868;8847.823043539158;8851.026387097414;8851.60167792451;5156.303898973628;5177.974110129463;5335.919961200585;5338.572914166857;5340.092184328634;3410.3179364127027;3498.9650103498893;3586.964130358715;3596.5504311961004;3598.8560457581666;1099.8680158381046;1102.685734565645;1103.707517507853;1119.8880111988817;1130.7060164357326;11598.826017609743;11710.941445292805;11719.414029298501;11722.238054526513;11725.00337471316;4517.796699148546;4538.410006699108;4645.210314246586;4671.27595222741;4672.836450724209;9232.353735238603;9247.213986811063;9252.8612070819;9255.074492550826;9277.396969196981;4375.562443755663;5197.66215196011;5201.531862132426;5202.609804264695;5204.323437953042;4018.3972404139613;4345.717528360647;4499.235130027903;4789.066132104276;4800.159972004955;3793.1844653399653;3798.772073675533;3825.1967086911504;3840.6927445804827;3909;1166.830809532613;1188.4057971014493;1192.1535709646075;1199.6401079676236;1201.1262430608106;745.414849343263;835.5404527509845;864.8195213533481;885.5616469847466;902.1024081039408;4642.78646348701;5724.79963201286;5853.882922341614;5947.672877991752;6012.278526576752;944.3389627260945;990.9157721593601;1001.602764144015;1012.3318609717551;1018.2007124407453;3783.4135708965155;3852.4028775539837;3886.4753258310334;3914.119323152268;3946.506686664167;10472.005159509834;10808.891911080946;10981.17641176915;11018.50416731249;11018.95319944604;10622.097121744739;10786.224821312635;10922.672319830259;10928.562857485611;10935;6836.350546698056;8176.877346839802;8270.172982701682;8275.675891857274;8368.20502052304;5764.538836893123;5772.278465191851;5778.913316300175;5791.160281759206;5935.940640593539;9723.465209413267;9877.06167914047;10243.846342304721;10244.975502449994;10252;10038.799224015625;10323.896761032294;10349.172066234532;10355.585776568852;10411.583536658753;6820.795376138723;7069.752558660422;7273.890891636735;7501.137369202612;7516.586587737727;8293.709720159766;8312.376571757264;8350.290225330671;8399.28606068491;8402.731262559197;7410.333070023508;7457.776266712007;7460.925390746023;7527;7622.31399174077;7142.535735177197;7235.819104522218;7279.963600181965;7334.083239595051;7390;6909.792706218822;6924.619145947021;7004.124484439445;7017.403520700797;7026.473014523829;4382.342648602735;4790.329353890388;4996.825111121093;5006.399232092027;5040.42035165946;4973.626977976739;5026.773795179152;5078.644494885322;5190.351206099238;5207.739613019409 05:54:48 INFO - PROCESS | 1666 | |4;prototype.html;307.08641790672743;311.21263204093634;316.1211831109516;317.11524845680543;321.14735377572555;4757.286407038946;5291.947080529194;5375.462453754628;5388.326459192601;5438.8912221755545;4419.315006174048;4591.517890621479;5133.435322114565;5386.384084774571;5399.865003374917;3383.4079036168696;3935.606439356056;4059.9594004060023;4079.8164082616204;4128.2981893078095;3496.440569508879;3614.548181477315;4050.2102090092444;4119.835206591748;4138.586141385867;3542.6714981881796;4074.7147699661036;4088.2028004539125;4090.6522945549664;4106.486689163855;3056.847157642131;3297.422950983568;3461.6884480396634;3840.6380076253045;4025.29557327468;3126.0621813455873;3379.442392005341;3389.9830500847584;3560.661737134968;3588.792892371199;5160.432352441228;5175.91305825777;5197.58419326453;5213;5221.373435187802;3736.0473079364588;4345.67407444443;4489.012417268195;4525.886852828673;4749.651495695526;4782.708668659478;4826.396700412449;5037.773300201499;5507.889842203173;5513.160722228055;3614;3935.8819235422984;3958.86143984962;3984.5417776955733;4012.4382586437923;5222.76497557607;5609.831705048814;5939.287285525765;5984.970075149597;6012.218411606463;3889.4360317753767;3908.726389152732;3912.765234085907;3927.9590908409295;3933.7443066200603;3848.8652897149027;3964.1764788197174;3969.206158768258;3982.0841206522664;4004.5594984551676;3980.482537270136;4184.602462766033;4618.861434156981;4629.282461218517;4684.297355396649;5368.624196306221;5752.395998420189;5755;5765.790972590033;5781.335146458127;1130.886911308879;1331.7735984882593;1361.7889227169806;1362.529927175123;1365.6244532753374;740.3336996702902;796.6417258574118;822.0874828939872;830.244477525461;840.815020695446;4125.628693417607;4864.318995340655;4917.459079501252;5017.974910125426;5092.770825312796;5051.812823986434;5172.663776854568;5349.4650534946195;5649.6327738696855;5762.567807414377;4588.632909367309;5056.342675452168;5202.372801735175;5353;5360.3299587551555;2688.260728299694;3032.6815684353264;3063.356695094011;3090.752739780812;3092.752579793611;5540.7783688652;5693.715314234271;6018.458338749533;6034.818955431344;6040.446115964228;4573.285018118206;5240.737963101829;5918.644881307136;6005.39946005396;6155.938440615626;5215.383231198341;5225.947740522623;6136.539759517965;6158.322584515699;6207.906881396692;5116.386033675983;5303.787848486171;5618.971905140448;5893.145493903275;5903.6830591266935;4675.041616468684;5107.157319042316;5565.916511252415;5765.94234057654;5875.529957603467;5054.646174767731;5096.541311282002;6060.484858787053;6185.443310102112;6191;3599.38810402227;3913.434626149563;3944.7238693292343;3965.861194858166;3978.3833505806647;4677.3451716759;5711.257536520226;5746.569007324551;5841.824745257649;5866.120081987736;4961.70229786214;5340.955655276455;5575.637583557056;5598.524125449219;5634.774264165713;5278.340207474066;5542.2795036644975;5603.327600687943;5616.994907724348;5671.234383358194;5231.0122153582915;5516.751746171512;5525.944740552543;5536.501714845522;5539.612227144223;5425.403205647297;5579.8884022320135;5630.5214056805635;5681.715914204356;5688.260526131577;4530.773461326855;4709.529047095263;4923.5814955729165;5317.521423071942;5625.746841392229 05:54:48 INFO - PROCESS | 1666 | |5;yui.html;683.1904193530667;721.4589058206345;736.9299916507937;753.506453273106;754.4454825703107;14279.643008924782;14289.07121037132;14451.060681055724;14473.5657930262;14847.777283340758;13167.012474064435;13203.2738199399;13238.205707657547;13266.270355130464;13274.469021239163;713.2510863593233;733.5305404541098;877.1272583779132;878.8286284174588;897.9461232326049;896.8475359188953;903.2638399704252;904.9049849765779;905.1220316293185;907.591583787295;824.2746383182791;951.8667386565886;952.3143336797494;952.5808644196566;955.0878910640356;742.0649981023948;921.1157289002566;947.5641205045653;949.8622699708538;953.427943234054;710.8862581986909;711.3313485323779;711.914570251568;712.540411434627;712.6151877985882;13976.440942362395;14009.159450433006;14033.158010519299;14049.43802247909;14049.929750351183;13416.664583385396;13513.918886489155;13573.52492662752;13730.65673358164;13782.828459580947;11422.771544569145;11955.820662690066;12066.457009434509;12176.57381991635;12669.74660506794;4382.772823609394;4433.689641725081;4447.0661161156195;4584.129015487047;4588.885277868047;76610.23389766087;76875.61562192209;77612;77891;81081.59459202725;10824.13406927444;10854.620088296871;10875.075618572351;10962.671119866416;11035.668929932115;21002.789972100083;21014.84418357005;21041.684374734392;21050.368488945354;21076.894615526824;7099.92900071003;7150.320719531636;7575.166731659513;7661.080670319486;7806.68773249075;3814.9809250953567;3842.346801043829;3935.5277366716177;3958.3666613342302;3991.6207960243732;3226.435373809621;3348.196432856097;3349.614794298687;3351.0952042948757;3358.4794356874722;635.8315046512678;645.3224114679567;651.9022146677942;656.2092678322609;656.8456412743002;559.1668414063017;618.4403115180769;619.6251267982896;625.8748250349948;634.0837489827247;8223.259906608433;8696.478211307342;8706.6517339307;8843.027267000625;8917.910820891837;552.8065177187952;609.0863704443335;615.1787363869195;622.2999125983268;627.5168120547245;3630.4735813306925;4093.5701751315682;4112.239235741337;4116.115035267432;4199.748015119103;82127.17872821314;85047;86724;91470;92260.53869730608;69672.65163674149;75206.62396687982;75872.62063689646;80825.59587202144;81688;63337;65800.68398632096;65967.6806463868;66058.66970665116;70797.64601177064;6765.797026089225;7943.84112317762;8012.278894899487;8056.758297251092;8205.548694821842;45770.771146144056;46303.30545041894;46473.21160730367;46909.53090469052;47201.527984719716;58075;64601.70796583948;64965;65377.34622653713;65421;11005.61480348216;11041.83437248458;11258.38078905668;11332.886671133183;11654.184207105422;11747.823782643438;11759.118066144902;11772.293662380285;12286;12343.716412463724;11030.724231894266;11069.778604428027;11411.030062444785;11415.143864210384;11546.018588420078;10889.346639201356;10898.945505272422;11032.944835276094;11163.441827908735;11627.883721163018;10625.25623206368;10880.075193608633;11084.113270938467;11155.72110697239;11259.324440533594;9140.862887056566;9186.08139186076;9266.21237194846;9425;9554.85667714971;7578.621068946421;9048.185663290336;9102.954485227532;9185.908140918773;9301.70098478077 05:54:48 INFO - PROCESS | 1666 | __end_tp_report 05:54:48 INFO - PROCESS | 1666 | __start_cc_report 05:54:48 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1119 05:54:48 INFO - PROCESS | 1666 | __end_cc_report 05:54:48 INFO - PROCESS | 1666 | __startTimestamp1464612888093__endTimestamp 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:54:48 INFO - PROCESS | 1666 | Number of tests: 6 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5676.39 Median:5843.74 stddev:649.26 (11.1%) stddev-sans-first:612.53 05:54:48 INFO - PROCESS | 1666 | Values: 2860.8 3242.8 3278.8 3428.8 3455.6 5535.1 5561.3 5635.7 5640.6 5665.3 5655.7 5690.5 5799.2 5842.9 5843.7 5104.6 5133.3 5152.1 5179.3 5267.7 5257.5 5282.7 5287.4 5298.6 5313.9 5077.6 5095.9 5098.7 5118.2 5156.5 4668.2 5096.5 5162.4 5271.8 5275.5 5075.3 5115.5 5147.2 5156.2 5187.7 5184.9 5516.5 5568.6 5615.5 5622.0 5232.2 6063.1 6106.6 6159.9 6163.6 6142.7 6150.0 6180.3 6184.5 6217.9 5827.8 5834.3 5842.1 5843.0 5863.1 6097.5 6144.2 6185.6 6191.3 6200.9 5841.1 6144.1 6150.3 6161.6 6178.8 6173.3 6174.7 6230.5 6252.3 6259.8 5695.0 6026.9 6089.7 6111.2 6153.3 6219.4 6220.4 6223.8 6231.1 6237.5 4928.4 4928.9 4929.3 4934.8 4955.7 4376.3 4391.0 4400.1 4451.4 4481.4 5628.9 5629.1 5632.2 5632.7 5695.3 6233.5 6244.2 6251.5 6269.2 6290.1 5521.6 5567.9 5584.9 5617.0 5638.1 5364.2 5366.1 5367.3 5371.8 5378.9 6293.1 6322.4 6333.7 6341.4 6350.4 6098.7 6107.7 6108.3 6140.0 6157.7 6349.0 6352.1 6364.9 6369.2 6374.5 4981.7 6073.4 6091.2 6094.5 6097.9 5680.6 6099.3 6148.5 6161.3 6207.8 6082.7 6083.9 6094.5 6098.5 6123.2 6024.0 6056.0 6088.5 6100.4 6104.1 5732.7 5829.8 5857.1 5893.4 5894.5 6043.1 6051.8 6056.9 6074.5 6134.0 4897.7 5741.4 5905.0 5918.8 5927.2 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:71670.21 Median:21700.69 stddev:153683.17 (708.2%) stddev-sans-first:154021.43 05:54:48 INFO - PROCESS | 1666 | Values: 510.5 517.8 519.6 528.7 550.2 29960.0 41855.0 43185.7 43259.0 43306.6 44250.0 44508.8 44533.8 44955.6 44964.0 8556.1 9489.0 9709.7 9723.9 9729.2 8679.6 11563.3 11746.6 11772.7 11953.4 11710.5 11785.1 11787.1 11788.0 11796.9 8620.7 11554.1 11687.6 11755.2 11782.0 11325.2 11424.2 11424.4 11426.9 11443.0 40519.6 40808.6 40889.0 40915.0 40939.6 3275.1 3587.0 3944.4 3987.5 4063.7 31168.4 32047.8 32493.9 32517.3 32639.0 3295.1 3424.4 3428.0 3459.5 3461.3 479127.0 479398.0 479810.0 480094.0 481951.0 54366.0 61638.7 62114.0 62184.8 62296.0 58299.0 65813.3 65946.7 65957.0 66652.0 16848.1 17030.6 17057.6 17092.4 17100.1 11740.5 12077.0 12850.9 13135.8 13197.0 17415.3 17453.7 17455.9 17480.9 17511.6 7435.8 7473.1 7502.3 7505.4 7511.1 941.8 1051.3 1107.9 1113.2 1118.1 29129.6 30254.8 30334.4 30357.5 31063.7 1121.9 1155.8 1156.7 1163.3 1207.4 14985.7 15432.8 15694.4 15711.9 15811.0 632177.0 633287.0 636240.0 637342.0 638571.0 60355.7 60439.0 60441.7 60517.1 60637.4 1316.2 1440.6 1450.9 1467.9 1498.2 24268.6 30058.4 31148.8 32042.0 32062.0 51969.7 53076.7 53203.7 53234.5 53241.2 588946.0 602435.0 603831.0 604427.0 604683.0 44473.6 44579.6 45984.0 46249.5 46358.5 40402.2 49113.8 49179.0 50037.7 50171.0 38563.6 40611.6 40627.1 40951.4 41555.8 38067.0 39718.8 39994.8 40119.2 41057.4 31828.8 38628.8 39407.8 39457.0 39692.6 33726.2 34642.1 35505.6 35508.5 35552.6 21141.9 23943.9 25210.9 25490.4 26335.6 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:33824.17 Median:19914.27 stddev:96990.41 (487.0%) stddev-sans-first:97229.98 05:54:48 INFO - PROCESS | 1666 | Values: 379.7 385.3 390.8 403.0 411.7 15780.7 17691.0 21639.0 21900.9 21918.0 20679.0 20939.8 21815.8 21915.8 21969.0 5315.6 5946.0 5982.5 6151.3 6161.1 6019.4 6084.3 6108.0 6111.1 6143.0 5863.4 6018.5 6029.1 6066.5 6073.6 5722.2 6008.1 6029.9 6051.1 6095.7 5875.7 5890.6 5911.1 5912.5 5916.3 13328.7 19895.2 23908.0 25168.5 25540.6 2198.2 2215.3 2292.3 2307.1 2576.5 9664.0 9837.2 10055.2 10149.7 10293.5 1535.3 1717.5 1792.3 1816.8 1825.7 92801.5 102377.0 104956.0 120048.4 126499.4 11636.7 11644.6 11650.3 11690.5 11846.4 14869.3 15001.4 15008.3 15018.0 15018.5 8461.4 8738.8 8741.6 8854.5 8880.4 8593.7 8621.3 8623.2 8628.5 8729.7 4473.2 4565.7 4573.7 4578.2 4636.3 1904.9 1945.2 1946.3 1988.5 1993.4 896.9 916.4 932.6 940.3 1012.8 20431.9 21501.6 21780.2 21841.4 21954.8 953.1 969.4 971.7 999.4 1059.3 11032.5 11336.0 11539.9 11544.8 11548.9 533458.0 584756.0 593909.4 594679.0 605531.0 96362.0 101892.0 122160.4 125716.0 139654.0 945.1 979.1 979.8 988.3 1068.4 18504.8 19891.7 19911.4 19976.4 19994.9 37252.1 47428.8 47714.3 48459.5 49556.8 75956.0 76316.0 76596.6 77126.2 77665.2 7081.8 7531.2 7570.4 7616.4 7785.1 12809.7 13010.9 13084.4 13220.5 13306.2 9513.0 10583.7 11260.8 11392.0 11460.8 11206.0 11259.3 11277.7 11518.5 11537.7 11294.6 11359.6 11457.6 11768.4 11801.1 6697.2 7524.7 7548.7 7575.4 7739.3 9925.4 9942.9 11432.9 11606.4 11688.7 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5663.77 Median:4672.06 stddev:3199.06 (68.5%) stddev-sans-first:3192.17 05:54:48 INFO - PROCESS | 1666 | Values: 1424.2 1550.9 1590.2 1816.5 1960.8 7918.8 8378.6 8392.1 8427.3 8607.1 8626.0 8694.9 8835.9 8839.2 8843.7 2360.9 2393.4 2395.9 2397.6 2398.7 2357.8 2386.1 2400.6 2402.7 2403.3 2342.3 2353.7 2364.1 2378.3 2380.1 1979.2 2335.0 2354.6 2368.4 2374.6 2316.7 2345.7 2347.6 2355.1 2355.8 8765.4 8847.6 8847.8 8851.0 8851.6 5156.3 5178.0 5335.9 5338.6 5340.1 3410.3 3499.0 3587.0 3596.6 3598.9 1099.9 1102.7 1103.7 1119.9 1130.7 11598.8 11710.9 11719.4 11722.2 11725.0 4517.8 4538.4 4645.2 4671.3 4672.8 9232.4 9247.2 9252.9 9255.1 9277.4 4375.6 5197.7 5201.5 5202.6 5204.3 4018.4 4345.7 4499.2 4789.1 4800.2 3793.2 3798.8 3825.2 3840.7 3909.0 1166.8 1188.4 1192.2 1199.6 1201.1 745.4 835.5 864.8 885.6 902.1 4642.8 5724.8 5853.9 5947.7 6012.3 944.3 990.9 1001.6 1012.3 1018.2 3783.4 3852.4 3886.5 3914.1 3946.5 10472.0 10808.9 10981.2 11018.5 11019.0 10622.1 10786.2 10922.7 10928.6 10935.0 6836.4 8176.9 8270.2 8275.7 8368.2 5764.5 5772.3 5778.9 5791.2 5935.9 9723.5 9877.1 10243.8 10245.0 10252.0 10038.8 10323.9 10349.2 10355.6 10411.6 6820.8 7069.8 7273.9 7501.1 7516.6 8293.7 8312.4 8350.3 8399.3 8402.7 7410.3 7457.8 7460.9 7527.0 7622.3 7142.5 7235.8 7280.0 7334.1 7390.0 6909.8 6924.6 7004.1 7017.4 7026.5 4382.3 4790.3 4996.8 5006.4 5040.4 4973.6 5026.8 5078.6 5190.4 5207.7 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4512.43 Median:5074.56 stddev:1405.01 (27.7%) stddev-sans-first:1373.03 05:54:48 INFO - PROCESS | 1666 | Values: 307.1 311.2 316.1 317.1 321.1 4757.3 5291.9 5375.5 5388.3 5438.9 4419.3 4591.5 5133.4 5386.4 5399.9 3383.4 3935.6 4060.0 4079.8 4128.3 3496.4 3614.5 4050.2 4119.8 4138.6 3542.7 4074.7 4088.2 4090.7 4106.5 3056.8 3297.4 3461.7 3840.6 4025.3 3126.1 3379.4 3390.0 3560.7 3588.8 5160.4 5175.9 5197.6 5213.0 5221.4 3736.0 4345.7 4489.0 4525.9 4749.7 4782.7 4826.4 5037.8 5507.9 5513.2 3614.0 3935.9 3958.9 3984.5 4012.4 5222.8 5609.8 5939.3 5985.0 6012.2 3889.4 3908.7 3912.8 3928.0 3933.7 3848.9 3964.2 3969.2 3982.1 4004.6 3980.5 4184.6 4618.9 4629.3 4684.3 5368.6 5752.4 5755.0 5765.8 5781.3 1130.9 1331.8 1361.8 1362.5 1365.6 740.3 796.6 822.1 830.2 840.8 4125.6 4864.3 4917.5 5018.0 5092.8 5051.8 5172.7 5349.5 5649.6 5762.6 4588.6 5056.3 5202.4 5353.0 5360.3 2688.3 3032.7 3063.4 3090.8 3092.8 5540.8 5693.7 6018.5 6034.8 6040.4 4573.3 5240.7 5918.6 6005.4 6155.9 5215.4 5225.9 6136.5 6158.3 6207.9 5116.4 5303.8 5619.0 5893.1 5903.7 4675.0 5107.2 5565.9 5765.9 5875.5 5054.6 5096.5 6060.5 6185.4 6191.0 3599.4 3913.4 3944.7 3965.9 3978.4 4677.3 5711.3 5746.6 5841.8 5866.1 4961.7 5341.0 5575.6 5598.5 5634.8 5278.3 5542.3 5603.3 5617.0 5671.2 5231.0 5516.8 5525.9 5536.5 5539.6 5425.4 5579.9 5630.5 5681.7 5688.3 4530.8 4709.5 4923.6 5317.5 5625.7 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17763.89 Median:619.03 stddev:24497.26 (3957.3%) stddev-sans-first:24532.40 05:54:48 INFO - PROCESS | 1666 | Values: 683.2 721.5 736.9 753.5 754.4 14279.6 14289.1 14451.1 14473.6 14847.8 13167.0 13203.3 13238.2 13266.3 13274.5 713.3 733.5 877.1 878.8 897.9 896.8 903.3 904.9 905.1 907.6 824.3 951.9 952.3 952.6 955.1 742.1 921.1 947.6 949.9 953.4 710.9 711.3 711.9 712.5 712.6 13976.4 14009.2 14033.2 14049.4 14049.9 13416.7 13513.9 13573.5 13730.7 13782.8 11422.8 11955.8 12066.5 12176.6 12669.7 4382.8 4433.7 4447.1 4584.1 4588.9 76610.2 76875.6 77612.0 77891.0 81081.6 10824.1 10854.6 10875.1 10962.7 11035.7 21002.8 21014.8 21041.7 21050.4 21076.9 7099.9 7150.3 7575.2 7661.1 7806.7 3815.0 3842.3 3935.5 3958.4 3991.6 3226.4 3348.2 3349.6 3351.1 3358.5 635.8 645.3 651.9 656.2 656.8 559.2 618.4 619.6 625.9 634.1 8223.3 8696.5 8706.7 8843.0 8917.9 552.8 609.1 615.2 622.3 627.5 3630.5 4093.6 4112.2 4116.1 4199.7 82127.2 85047.0 86724.0 91470.0 92260.5 69672.7 75206.6 75872.6 80825.6 81688.0 63337.0 65800.7 65967.7 66058.7 70797.6 6765.8 7943.8 8012.3 8056.8 8205.5 45770.8 46303.3 46473.2 46909.5 47201.5 58075.0 64601.7 64965.0 65377.3 65421.0 11005.6 11041.8 11258.4 11332.9 11654.2 11747.8 11759.1 11772.3 12286.0 12343.7 11030.7 11069.8 11411.0 11415.1 11546.0 10889.3 10898.9 11032.9 11163.4 11627.9 10625.3 10880.1 11084.1 11155.7 11259.3 9140.9 9186.1 9266.2 9425.0 9554.9 7578.6 9048.2 9103.0 9185.9 9301.7 05:54:48 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:54:48 INFO - PROCESS | 1666 | 05:54:48 INFO - TEST-INFO | 1666: exit 0 05:54:48 INFO - TEST-OK | dromaeo_css | took 1115209ms 05:54:48 INFO - TEST-START | kraken 05:54:48 INFO - operating with platform_type : mac_ 05:54:48 INFO - Initialising browser for kraken test... 05:54:48 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/tmp9HWlO7/profile http://localhost:49277/getInfo.html) 05:54:50 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:54:50 INFO - PROCESS | 1672 | colorDepth:24 05:54:50 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:54:50 INFO - PROCESS | 1672 | __metrics 05:54:51 INFO - PROCESS | 1672 | 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" 05:54:51 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:54:51 INFO - TEST-INFO | 1672: exit 0 05:54:51 INFO - Browser initialized. 05:54:51 INFO - Running cycle 1/1 for kraken test... 05:54:51 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/T/tmp9HWlO7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:55:03 INFO - PROCESS | 1673 | RSS: Main: 241709056 05:55:03 INFO - PROCESS | 1673 | 05:55:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 05:55:07 INFO - PROCESS | 1673 | RSS: Main: 291860480 05:55:07 INFO - PROCESS | 1673 | 05:55:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 05:55:10 INFO - PROCESS | 1673 | RSS: Main: 301436928 05:55:10 INFO - PROCESS | 1673 | 05:55:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 05:55:14 INFO - PROCESS | 1673 | RSS: Main: 389279744 05:55:14 INFO - PROCESS | 1673 | 05:55:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 05:55:17 INFO - PROCESS | 1673 | RSS: Main: 290566144 05:55:17 INFO - PROCESS | 1673 | 05:55:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:55:20 INFO - PROCESS | 1673 | RSS: Main: 294469632 05:55:20 INFO - PROCESS | 1673 | 05:55:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 05:55:23 INFO - PROCESS | 1673 | RSS: Main: 331759616 05:55:23 INFO - PROCESS | 1673 | 05:55:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 05:55:27 INFO - PROCESS | 1673 | RSS: Main: 334356480 05:55:27 INFO - PROCESS | 1673 | 05:55:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 05:55:30 INFO - PROCESS | 1673 | RSS: Main: 331026432 05:55:30 INFO - PROCESS | 1673 | 05:55:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:55:33 INFO - PROCESS | 1673 | RSS: Main: 311754752 05:55:33 INFO - PROCESS | 1673 | 05:55:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:55:35 INFO - PROCESS | 1673 | RSS: Main: 317743104 05:55:35 INFO - PROCESS | 1673 | 05:55:37 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:55:38 INFO - PROCESS | 1673 | RSS: Main: 322379776 05:55:38 INFO - PROCESS | 1673 | 05:55:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:55:41 INFO - PROCESS | 1673 | RSS: Main: 325074944 05:55:41 INFO - PROCESS | 1673 | 05:55:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:55:45 INFO - PROCESS | 1673 | RSS: Main: 326569984 05:55:45 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 05:55:47 INFO - PROCESS | 1673 | RSS: Main: 370200576 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | __start_tp_report 05:55:47 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 05:55:47 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 05:55:47 INFO - PROCESS | 1673 | |i|pagename|runs| 05:55:47 INFO - PROCESS | 1673 | |0;ai-astar;111;124;167;149;112;113;113;110;112;112 05:55:47 INFO - PROCESS | 1673 | |1;audio-beat-detection;154;117;119;169;122;104;165;143;177;142 05:55:47 INFO - PROCESS | 1673 | |2;audio-dft;182;182;183;183;184;182;182;181;181;180 05:55:47 INFO - PROCESS | 1673 | |3;audio-fft;80;73;58;105;57;59;63;64;102;79 05:55:47 INFO - PROCESS | 1673 | |4;audio-oscillator;89;86;92;71;71;68;93;70;67;99 05:55:47 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;84;89;85;88;86;87;85;90;119 05:55:47 INFO - PROCESS | 1673 | |6;imaging-darkroom;95;94;95;93;93;107;149;124;94;93 05:55:47 INFO - PROCESS | 1673 | |7;imaging-desaturate;81;86;87;87;84;83;85;104;134;145 05:55:47 INFO - PROCESS | 1673 | |8;json-parse-financial;57;57;57;57;57;55;55;55;54;56 05:55:47 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;68;74;80;75;70;73;70;69;69;69 05:55:47 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;85;85;85;85;84;84;84;86;121;87 05:55:47 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;140;151;146;136;141;142;125;153;148;155 05:55:47 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;165;166;160;162;215;221;162;166;224;180 05:55:47 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;66;68;66;65;64;66;67;66;65 05:55:47 INFO - PROCESS | 1673 | __end_tp_report 05:55:47 INFO - PROCESS | 1673 | __start_cc_report 05:55:47 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1988 05:55:47 INFO - PROCESS | 1673 | __end_cc_report 05:55:47 INFO - PROCESS | 1673 | __startTimestamp1464612947554__endTimestamp 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | ------- Summary: start ------- 05:55:47 INFO - PROCESS | 1673 | Number of tests: 14 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:122.30 Median:113.00 stddev:19.68 (17.4%) stddev-sans-first:20.44 05:55:47 INFO - PROCESS | 1673 | Values: 111.0 124.0 167.0 149.0 112.0 113.0 113.0 110.0 112.0 112.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:148.50 stddev:24.99 (16.8%) stddev-sans-first:26.07 05:55:47 INFO - PROCESS | 1673 | Values: 154.0 117.0 119.0 169.0 122.0 104.0 165.0 143.0 177.0 142.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:182.00 Median:182.00 stddev:1.15 (0.6%) stddev-sans-first:1.22 05:55:47 INFO - PROCESS | 1673 | Values: 182.0 182.0 183.0 183.0 184.0 182.0 182.0 181.0 181.0 180.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:74.00 Median:63.50 stddev:17.63 (27.8%) stddev-sans-first:18.57 05:55:47 INFO - PROCESS | 1673 | Values: 80.0 73.0 58.0 105.0 57.0 59.0 63.0 64.0 102.0 79.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:87.50 stddev:12.30 (14.1%) stddev-sans-first:12.67 05:55:47 INFO - PROCESS | 1673 | Values: 89.0 86.0 92.0 71.0 71.0 68.0 93.0 70.0 67.0 99.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:87.50 stddev:10.33 (11.8%) stddev-sans-first:10.93 05:55:47 INFO - PROCESS | 1673 | Values: 88.0 84.0 89.0 85.0 88.0 86.0 87.0 85.0 90.0 119.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:103.70 Median:93.50 stddev:18.73 (20.0%) stddev-sans-first:19.60 05:55:47 INFO - PROCESS | 1673 | Values: 95.0 94.0 95.0 93.0 93.0 107.0 149.0 124.0 94.0 93.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:84.50 stddev:23.10 (27.3%) stddev-sans-first:23.71 05:55:47 INFO - PROCESS | 1673 | Values: 81.0 86.0 87.0 87.0 84.0 83.0 85.0 104.0 134.0 145.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:57.00 stddev:1.15 (2.0%) stddev-sans-first:1.17 05:55:47 INFO - PROCESS | 1673 | Values: 57.0 57.0 57.0 57.0 57.0 55.0 55.0 55.0 54.0 56.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.50 stddev:3.77 (5.3%) stddev-sans-first:3.76 05:55:47 INFO - PROCESS | 1673 | Values: 68.0 74.0 80.0 75.0 70.0 73.0 70.0 69.0 69.0 69.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:85.00 stddev:11.42 (13.4%) stddev-sans-first:12.04 05:55:47 INFO - PROCESS | 1673 | Values: 85.0 85.0 85.0 85.0 84.0 84.0 84.0 86.0 121.0 87.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:147.00 stddev:8.97 (6.1%) stddev-sans-first:9.41 05:55:47 INFO - PROCESS | 1673 | Values: 140.0 151.0 146.0 136.0 141.0 142.0 125.0 153.0 148.0 155.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:173.00 stddev:26.80 (15.5%) stddev-sans-first:27.70 05:55:47 INFO - PROCESS | 1673 | Values: 165.0 166.0 160.0 162.0 215.0 221.0 162.0 166.0 224.0 180.0 05:55:47 INFO - PROCESS | 1673 | 05:55:47 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 05:55:47 INFO - PROCESS | 1673 | Values: 66.0 66.0 68.0 66.0 65.0 64.0 66.0 67.0 66.0 65.0 05:55:47 INFO - PROCESS | 1673 | -------- Summary: end -------- 05:55:47 INFO - PROCESS | 1673 | 05:55:48 INFO - TEST-INFO | 1673: exit 0 05:55:48 INFO - TEST-OK | kraken | took 59673ms 05:55:48 INFO - SUITE-END | took 1174s 05:55:48 INFO - Completed test suite (00:19:35) 05:55:48 INFO - javascript benchmark 05:55:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2860.7711383089354, 3242.832580271102, 3278.7868788528745, 3428.7942723436613, 3455.602605700345, 5535.142052981794, 5561.33264008319, 5635.718214089289, 5640.605157638967, 5665.29183852018, 5655.717214139287, 5690.5447564194865, 5799.159121927318, 5842.941570584293, 5843.737031833567, 5104.617153713468, 5133.3326667533165, 5152.072626927152, 5179.300794392747, 5267.65760225586, 5257.474252574751, 5282.656627319212, 5287.444818294081, 5298.576113910897, 5313.867153321178, 5077.619178561604, 5095.898082038376, 5098.719570423626, 5118.232265160255, 5156.458571849939, 4668.206404911173, 5096.51583099605, 5162.380514338266, 5271.815486457956, 5275.472452754732, 5075.289459475676, 5115.488451154854, 5147.202183661539, 5156.226566015128, 5187.714675692835, 5184.937087897009, 5516.475934786189, 5568.554515638739, 5615.466530679579, 5621.971890140564, 5232.2413250078525, 6063.130336476962, 6106.633601983851, 6159.938400615982, 6163.568550201488, 6142.692865356759, 6149.969250153766, 6180.258368995705, 6184.474319682832, 6217.906731399033, 5827.825165245049, 5834.2998840139035, 5842.065269556851, 5843.0, 5863.091220860688, 6097.494431795059, 6144.15675297409, 6185.628862268279, 6191.318954914956, 6200.906986395207, 5841.1238314253205, 6144.126041557279, 6150.261968563801, 6161.630302181883, 6178.814635560941, 6173.290071641819, 6174.6912654368, 6230.470410015108, 6252.3122456529745, 6259.812205633838, 5694.971525142348, 6026.939730602726, 6089.725962331706, 6111.236095488064, 6153.261608606996, 6219.378062193835, 6220.440160385587, 6223.813285601439, 6231.065340198916, 6237.4698150656795, 4928.433230178575, 4928.901421971612, 4929.260610908393, 4934.753262336941, 4955.677880937727, 4376.343548467756, 4390.978045109754, 4400.075984043323, 4451.3545535897765, 4481.439820022497, 5628.887422251532, 5629.127485239795, 5632.183333416713, 5632.746526406321, 5695.288088988877, 6233.501319894398, 6244.224092714221, 6251.468625166821, 6269.247690277196, 6290.119383286344, 5521.641093328921, 5567.860803479945, 5584.86037849057, 5617.0, 5638.126090455986, 5364.222187782828, 5366.060939335679, 5367.329083864517, 5371.838844834661, 5378.892422151614, 6293.087502312139, 6322.367763223733, 6333.714982825876, 6341.4292713656, 6350.396712312323, 6098.704546243851, 6107.725152368243, 6108.311716708011, 6139.969300153471, 6157.692115394302, 6349.0, 6352.110704501466, 6364.904526432199, 6369.172007638977, 6374.521910856612, 4981.676191047571, 6073.392660734068, 6091.208142941389, 6094.481969032682, 6097.908531371944, 5680.630759000652, 6099.298580663103, 6148.508119350352, 6161.260648722182, 6207.844803879759, 6082.7262773174425, 6083.908741368971, 6094.54290928191, 6098.512119030378, 6123.203983482297, 6024.0, 6056.0, 6088.512918966383, 6100.359462256577, 6104.084387341934, 5732.656040637575, 5829.76680932758, 5857.092150716646, 5893.381194974466, 5894.498967587631, 6043.12374705674, 6051.80175713469, 6056.905726312452, 6074.514038876968, 6133.969330153321, 4897.657163998486, 5741.397153299011, 5904.9704751475965, 5918.7928422505, 5927.199828023162], "value": 5634.304492373307, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.50481033397614, 517.7799435240022, 519.633658270919, 528.711852040638, 550.2489102375259, 29960.0, 41854.95362615936, 43185.704428867146, 43259.0, 43306.56693433065, 44250.0, 44508.77745611275, 44533.77733111338, 44955.550444495544, 44964.0, 8556.101609331025, 9488.95255523723, 9709.660161894335, 9723.854142187873, 9729.221662267037, 8679.566021698922, 11563.306201627887, 11746.588869389572, 11772.705682357924, 11953.40232988347, 11710.53157873684, 11785.057195424346, 11787.11596630252, 11788.0, 11796.938275555198, 8620.698275560388, 11554.075673946065, 11687.5909343173, 11755.235909665844, 11782.0, 11325.150613704005, 11424.200305978586, 11424.42877856104, 11426.94286528562, 11442.970132688099, 40519.59480405188, 40808.591914080775, 40888.977775555555, 40914.9771255718, 40939.59060409388, 3275.132089996153, 3587.0, 3944.3886197639413, 3987.48162738845, 4063.7155399122075, 31168.376632467225, 32047.839760801045, 32493.862714805105, 32517.349653006808, 32639.0, 3295.143262751678, 3424.434968230239, 3428.0230134411822, 3459.515667806509, 3461.3250416168858, 479127.0, 479398.0, 479810.0, 480094.0, 481951.0, 54366.0, 61638.691806541574, 62114.0, 62184.75630487455, 62296.0, 58299.0, 65813.34186658169, 65946.67026664836, 65957.01064484037, 66652.0, 16848.062472815538, 17030.57423564421, 17057.573560660836, 17092.350685643745, 17100.144992750313, 11740.471678774305, 12076.973457256232, 12850.871491285154, 13135.802962955564, 13197.0, 17415.303387864344, 17453.65092698164, 17455.912720436318, 17480.863743856607, 17511.64976700459, 7435.839768098536, 7473.140588832245, 7502.324790768748, 7505.399568034544, 7511.098668159856, 941.8304705153138, 1051.2588625019343, 1107.894749998749, 1113.1706878375662, 1118.1446193661855, 29129.56305655417, 30254.84872575623, 30334.393312134074, 30357.54463683002, 31063.71916774154, 1121.8653761548503, 1155.8324043013715, 1156.7455159864985, 1163.2845799833042, 1207.4144040140525, 14985.700285994219, 15432.845671543366, 15694.372225110868, 15711.921440392725, 15811.0, 632177.0, 633287.0, 636240.0, 637342.0, 638571.0, 60355.69822150949, 60439.0, 60441.69779151076, 60517.0922436155, 60637.393626063174, 1316.1576590981779, 1440.6038339456734, 1450.8766754825747, 1467.8752306054105, 1498.1760031982149, 24268.63597046081, 30058.444477746667, 31148.844255778575, 32042.0, 32062.0, 51969.740151299, 53076.73461632667, 53203.733981329846, 53234.4676553245, 53241.20138198008, 588946.0, 602435.0, 603831.0, 604427.0, 604683.0, 44473.55526444694, 44579.55420445754, 45984.0, 46249.53750462587, 46358.53641463677, 40402.191956160124, 49113.772155696395, 49179.0, 50037.74981125071, 50170.996580068924, 38563.61436385712, 40611.59388406078, 40627.1090691854, 40951.36210971514, 41555.792221038704, 38067.0, 39718.80140599279, 39994.800025999684, 40119.1976160481, 41057.384139237336, 31828.840855795574, 38628.80685596554, 39407.80296098501, 39457.0, 39692.603073968894, 33726.156846079044, 34642.133946651535, 35505.64494355127, 35508.467372989944, 35552.64447355493, 21141.89429052845, 23943.880280598485, 25210.865511052412, 25490.362740931625, 26335.604965925908], "value": 21079.785988999425, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.68865530265185, 385.33337326425277, 390.77530420008486, 402.9899252518685, 411.7118017387829, 15780.684386312267, 17691.0, 21639.0, 21900.89049554752, 21918.013689383977, 20679.0, 20939.848308343036, 21815.781842181572, 21915.780842191572, 21969.0, 5315.574754019669, 5945.999999999989, 5982.461578457938, 6151.261848578177, 6161.137440758297, 6019.367966363535, 6084.330723620398, 6108.0, 6111.052786818051, 6142.96928515359, 5863.355030946614, 6018.5254612619965, 6029.095635654643, 6066.484348830332, 6073.60521566099, 5722.227499287585, 6008.128821320905, 6029.939700602981, 6051.1225872248715, 6095.6952152392205, 5875.706214689291, 5890.617109887844, 5911.054231323011, 5912.526997840162, 5916.26046744157, 13328.666783330398, 19895.20419183231, 23908.04367825285, 25168.49663006748, 25540.616890746653, 2198.1976578548715, 2215.29110684581, 2292.3123063081007, 2307.077169132344, 2576.4718232762425, 9664.0, 9837.19605432798, 10055.195584353234, 10149.695509134737, 10293.485325733684, 1535.2707463954644, 1717.4590004148654, 1792.3010026089835, 1816.7638207033, 1825.6805059114602, 92801.53599231961, 102377.0, 104956.0, 120048.39975800065, 126499.36750316282, 11636.709082273012, 11644.592439264585, 11650.30098194111, 11690.532378704927, 11846.407679615984, 14869.256537173098, 15001.399944002336, 15008.324625391884, 15018.023828451116, 15018.474353397578, 8461.365397595082, 8738.825223495622, 8741.606627701642, 8854.513001784964, 8880.378373513791, 8593.742187734379, 8621.31029517637, 8623.180797824196, 8628.52543110135, 8729.650813967497, 4473.194824931544, 4565.726056436624, 4573.738497370444, 4578.175928332932, 4636.281376386665, 1904.9238030478903, 1945.1538580717433, 1946.3479734289172, 1988.5227545389291, 1993.4119434766778, 896.8699538566872, 916.3814425262921, 932.5896605493562, 940.2618944128826, 1012.8075665623508, 20431.8978405107, 21501.559750439854, 21780.237691680715, 21841.427155599602, 21954.78045219559, 953.0897992417225, 969.4474149734583, 971.7133445633555, 999.3703966501058, 1059.3273271472517, 11032.50353734084, 11336.0, 11539.942300288612, 11544.826827597593, 11548.884511154949, 533458.0, 584756.0, 593909.426340152, 594679.0266048642, 605531.0, 96362.0, 101891.98108018973, 122160.38919805344, 125716.0, 139654.0, 945.1021529546958, 979.1090108001684, 979.7942432089199, 988.3476905242649, 1068.3589846092282, 18504.81495185031, 19891.701624475932, 19911.402657920287, 19976.400707978784, 19994.90002549978, 37252.06869828276, 47428.7628561855, 47714.28428573505, 48459.5154048455, 49556.75221623869, 75956.0, 76316.0, 76596.61701691679, 77126.22873771415, 77665.2233477658, 7081.787546373617, 7531.209223031503, 7570.394368450621, 7616.428767842323, 7785.104712957856, 12809.679758006125, 13010.869891300967, 13084.411201495765, 13220.471181152647, 13306.201627902357, 9513.001134880738, 10583.682489525327, 11260.831087533857, 11391.974722275036, 11460.828087578526, 11206.0, 11259.268147570381, 11277.661670149908, 11518.53925842957, 11537.711557211136, 11294.58817647794, 11359.602413915472, 11457.59898403552, 11768.411579420823, 11801.114916381477, 6697.196336439659, 7524.699012039458, 7548.698052078075, 7575.393968482618, 7739.264769846855, 9925.404475731479, 9942.850857237003, 11432.885671143182, 11606.361650109326, 11688.70778230517], "value": 9527.884556958179, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.238032652531, 1550.914699691517, 1590.2287390615552, 1816.5277027972722, 1960.8235258826712, 7918.84162316754, 8378.62296196671, 8392.118827523098, 8427.283680887123, 8607.09625489324, 8625.956870215656, 8694.869576956335, 8835.911640883589, 8839.204471597555, 8843.734687959372, 2360.940976475589, 2393.377721792338, 2395.928122156329, 2397.5564520563753, 2398.7241467231315, 2357.787799098081, 2386.0575072846214, 2400.627902675088, 2402.6756387887585, 2403.267003563912, 2342.344143639775, 2353.6587194856734, 2364.137089962169, 2378.286514045788, 2380.059876348841, 1979.2478858033999, 2334.9883250583807, 2354.6114891042957, 2368.3723813189517, 2374.643803429482, 2316.652502124678, 2345.7185137783404, 2347.5657003454244, 2355.140373763581, 2355.8468699534646, 8765.430247033986, 8847.557622118868, 8847.823043539158, 8851.026387097414, 8851.60167792451, 5156.303898973628, 5177.974110129463, 5335.919961200585, 5338.572914166857, 5340.092184328634, 3410.3179364127027, 3498.9650103498893, 3586.964130358715, 3596.5504311961004, 3598.8560457581666, 1099.8680158381046, 1102.685734565645, 1103.707517507853, 1119.8880111988817, 1130.7060164357326, 11598.826017609743, 11710.941445292805, 11719.414029298501, 11722.238054526513, 11725.00337471316, 4517.796699148546, 4538.410006699108, 4645.210314246586, 4671.27595222741, 4672.836450724209, 9232.353735238603, 9247.213986811063, 9252.8612070819, 9255.074492550826, 9277.396969196981, 4375.562443755663, 5197.66215196011, 5201.531862132426, 5202.609804264695, 5204.323437953042, 4018.3972404139613, 4345.717528360647, 4499.235130027903, 4789.066132104276, 4800.159972004955, 3793.1844653399653, 3798.772073675533, 3825.1967086911504, 3840.6927445804827, 3909.0, 1166.830809532613, 1188.4057971014493, 1192.1535709646075, 1199.6401079676236, 1201.1262430608106, 745.414849343263, 835.5404527509845, 864.8195213533481, 885.5616469847466, 902.1024081039408, 4642.78646348701, 5724.79963201286, 5853.882922341614, 5947.672877991752, 6012.278526576752, 944.3389627260945, 990.9157721593601, 1001.602764144015, 1012.3318609717551, 1018.2007124407453, 3783.4135708965155, 3852.4028775539837, 3886.4753258310334, 3914.119323152268, 3946.506686664167, 10472.005159509834, 10808.891911080946, 10981.17641176915, 11018.50416731249, 11018.95319944604, 10622.097121744739, 10786.224821312635, 10922.672319830259, 10928.562857485611, 10935.0, 6836.350546698056, 8176.877346839802, 8270.172982701682, 8275.675891857274, 8368.20502052304, 5764.538836893123, 5772.278465191851, 5778.913316300175, 5791.160281759206, 5935.940640593539, 9723.465209413267, 9877.06167914047, 10243.846342304721, 10244.975502449994, 10252.0, 10038.799224015625, 10323.896761032294, 10349.172066234532, 10355.585776568852, 10411.583536658753, 6820.795376138723, 7069.752558660422, 7273.890891636735, 7501.137369202612, 7516.586587737727, 8293.709720159766, 8312.376571757264, 8350.290225330671, 8399.28606068491, 8402.731262559197, 7410.333070023508, 7457.776266712007, 7460.925390746023, 7527.0, 7622.31399174077, 7142.535735177197, 7235.819104522218, 7279.963600181965, 7334.083239595051, 7390.0, 6909.792706218822, 6924.619145947021, 7004.124484439445, 7017.403520700797, 7026.473014523829, 4382.342648602735, 4790.329353890388, 4996.825111121093, 5006.399232092027, 5040.42035165946, 4973.626977976739, 5026.773795179152, 5078.644494885322, 5190.351206099238, 5207.739613019409], "value": 4550.899140638623, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.08641790672743, 311.21263204093634, 316.1211831109516, 317.11524845680543, 321.14735377572555, 4757.286407038946, 5291.947080529194, 5375.462453754628, 5388.326459192601, 5438.8912221755545, 4419.315006174048, 4591.517890621479, 5133.435322114565, 5386.384084774571, 5399.865003374917, 3383.4079036168696, 3935.606439356056, 4059.9594004060023, 4079.8164082616204, 4128.2981893078095, 3496.440569508879, 3614.548181477315, 4050.2102090092444, 4119.835206591748, 4138.586141385867, 3542.6714981881796, 4074.7147699661036, 4088.2028004539125, 4090.6522945549664, 4106.486689163855, 3056.847157642131, 3297.422950983568, 3461.6884480396634, 3840.6380076253045, 4025.29557327468, 3126.0621813455873, 3379.442392005341, 3389.9830500847584, 3560.661737134968, 3588.792892371199, 5160.432352441228, 5175.91305825777, 5197.58419326453, 5213.0, 5221.373435187802, 3736.0473079364588, 4345.67407444443, 4489.012417268195, 4525.886852828673, 4749.651495695526, 4782.708668659478, 4826.396700412449, 5037.773300201499, 5507.889842203173, 5513.160722228055, 3614.0, 3935.8819235422984, 3958.86143984962, 3984.5417776955733, 4012.4382586437923, 5222.76497557607, 5609.831705048814, 5939.287285525765, 5984.970075149597, 6012.218411606463, 3889.4360317753767, 3908.726389152732, 3912.765234085907, 3927.9590908409295, 3933.7443066200603, 3848.8652897149027, 3964.1764788197174, 3969.206158768258, 3982.0841206522664, 4004.5594984551676, 3980.482537270136, 4184.602462766033, 4618.861434156981, 4629.282461218517, 4684.297355396649, 5368.624196306221, 5752.395998420189, 5755.0, 5765.790972590033, 5781.335146458127, 1130.886911308879, 1331.7735984882593, 1361.7889227169806, 1362.529927175123, 1365.6244532753374, 740.3336996702902, 796.6417258574118, 822.0874828939872, 830.244477525461, 840.815020695446, 4125.628693417607, 4864.318995340655, 4917.459079501252, 5017.974910125426, 5092.770825312796, 5051.812823986434, 5172.663776854568, 5349.4650534946195, 5649.6327738696855, 5762.567807414377, 4588.632909367309, 5056.342675452168, 5202.372801735175, 5353.0, 5360.3299587551555, 2688.260728299694, 3032.6815684353264, 3063.356695094011, 3090.752739780812, 3092.752579793611, 5540.7783688652, 5693.715314234271, 6018.458338749533, 6034.818955431344, 6040.446115964228, 4573.285018118206, 5240.737963101829, 5918.644881307136, 6005.39946005396, 6155.938440615626, 5215.383231198341, 5225.947740522623, 6136.539759517965, 6158.322584515699, 6207.906881396692, 5116.386033675983, 5303.787848486171, 5618.971905140448, 5893.145493903275, 5903.6830591266935, 4675.041616468684, 5107.157319042316, 5565.916511252415, 5765.94234057654, 5875.529957603467, 5054.646174767731, 5096.541311282002, 6060.484858787053, 6185.443310102112, 6191.0, 3599.38810402227, 3913.434626149563, 3944.7238693292343, 3965.861194858166, 3978.3833505806647, 4677.3451716759, 5711.257536520226, 5746.569007324551, 5841.824745257649, 5866.120081987736, 4961.70229786214, 5340.955655276455, 5575.637583557056, 5598.524125449219, 5634.774264165713, 5278.340207474066, 5542.2795036644975, 5603.327600687943, 5616.994907724348, 5671.234383358194, 5231.0122153582915, 5516.751746171512, 5525.944740552543, 5536.501714845522, 5539.612227144223, 5425.403205647297, 5579.8884022320135, 5630.5214056805635, 5681.715914204356, 5688.260526131577, 4530.773461326855, 4709.529047095263, 4923.5814955729165, 5317.521423071942, 5625.746841392229], "value": 4063.949194195559, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.1904193530667, 721.4589058206345, 736.9299916507937, 753.506453273106, 754.4454825703107, 14279.643008924782, 14289.07121037132, 14451.060681055724, 14473.5657930262, 14847.777283340758, 13167.012474064435, 13203.2738199399, 13238.205707657547, 13266.270355130464, 13274.469021239163, 713.2510863593233, 733.5305404541098, 877.1272583779132, 878.8286284174588, 897.9461232326049, 896.8475359188953, 903.2638399704252, 904.9049849765779, 905.1220316293185, 907.591583787295, 824.2746383182791, 951.8667386565886, 952.3143336797494, 952.5808644196566, 955.0878910640356, 742.0649981023948, 921.1157289002566, 947.5641205045653, 949.8622699708538, 953.427943234054, 710.8862581986909, 711.3313485323779, 711.914570251568, 712.540411434627, 712.6151877985882, 13976.440942362395, 14009.159450433006, 14033.158010519299, 14049.43802247909, 14049.929750351183, 13416.664583385396, 13513.918886489155, 13573.52492662752, 13730.65673358164, 13782.828459580947, 11422.771544569145, 11955.820662690066, 12066.457009434509, 12176.57381991635, 12669.74660506794, 4382.772823609394, 4433.689641725081, 4447.0661161156195, 4584.129015487047, 4588.885277868047, 76610.23389766087, 76875.61562192209, 77612.0, 77891.0, 81081.59459202725, 10824.13406927444, 10854.620088296871, 10875.075618572351, 10962.671119866416, 11035.668929932115, 21002.789972100083, 21014.84418357005, 21041.684374734392, 21050.368488945354, 21076.894615526824, 7099.92900071003, 7150.320719531636, 7575.166731659513, 7661.080670319486, 7806.68773249075, 3814.9809250953567, 3842.346801043829, 3935.5277366716177, 3958.3666613342302, 3991.6207960243732, 3226.435373809621, 3348.196432856097, 3349.614794298687, 3351.0952042948757, 3358.4794356874722, 635.8315046512678, 645.3224114679567, 651.9022146677942, 656.2092678322609, 656.8456412743002, 559.1668414063017, 618.4403115180769, 619.6251267982896, 625.8748250349948, 634.0837489827247, 8223.259906608433, 8696.478211307342, 8706.6517339307, 8843.027267000625, 8917.910820891837, 552.8065177187952, 609.0863704443335, 615.1787363869195, 622.2999125983268, 627.5168120547245, 3630.4735813306925, 4093.5701751315682, 4112.239235741337, 4116.115035267432, 4199.748015119103, 82127.17872821314, 85047.0, 86724.0, 91470.0, 92260.53869730608, 69672.65163674149, 75206.62396687982, 75872.62063689646, 80825.59587202144, 81688.0, 63337.0, 65800.68398632096, 65967.6806463868, 66058.66970665116, 70797.64601177064, 6765.797026089225, 7943.84112317762, 8012.278894899487, 8056.758297251092, 8205.548694821842, 45770.771146144056, 46303.30545041894, 46473.21160730367, 46909.53090469052, 47201.527984719716, 58075.0, 64601.70796583948, 64965.0, 65377.34622653713, 65421.0, 11005.61480348216, 11041.83437248458, 11258.38078905668, 11332.886671133183, 11654.184207105422, 11747.823782643438, 11759.118066144902, 11772.293662380285, 12286.0, 12343.716412463724, 11030.724231894266, 11069.778604428027, 11411.030062444785, 11415.143864210384, 11546.018588420078, 10889.346639201356, 10898.945505272422, 11032.944835276094, 11163.441827908735, 11627.883721163018, 10625.25623206368, 10880.075193608633, 11084.113270938467, 11155.72110697239, 11259.324440533594, 9140.862887056566, 9186.08139186076, 9266.21237194846, 9425.0, 9554.85667714971, 7578.621068946421, 9048.185663290336, 9102.954485227532, 9185.908140918773, 9301.70098478077], "value": 6887.931740307484, "unit": "score"}], "name": "dromaeo_css", "value": 7241.263372545398, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 124.0, 167.0, 149.0, 112.0, 113.0, 113.0, 110.0, 112.0, 112.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 117.0, 119.0, 169.0, 122.0, 104.0, 165.0, 143.0, 177.0, 142.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 183.0, 183.0, 184.0, 182.0, 182.0, 181.0, 181.0, 180.0], "value": 182.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 58.0, 105.0, 57.0, 59.0, 63.0, 64.0, 102.0, 79.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 92.0, 71.0, 71.0, 68.0, 93.0, 70.0, 67.0, 99.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 89.0, 85.0, 88.0, 86.0, 87.0, 85.0, 90.0, 119.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 93.0, 93.0, 107.0, 149.0, 124.0, 94.0, 93.0], "value": 103.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 87.0, 87.0, 84.0, 83.0, 85.0, 104.0, 134.0, 145.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 57.0, 57.0, 55.0, 55.0, 55.0, 54.0, 56.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 80.0, 75.0, 70.0, 73.0, 70.0, 69.0, 69.0, 69.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 85.0, 84.0, 84.0, 84.0, 86.0, 121.0, 87.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 151.0, 146.0, 136.0, 141.0, 142.0, 125.0, 153.0, 148.0, 155.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 160.0, 162.0, 215.0, 221.0, 162.0, 166.0, 224.0, 180.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 66.0, 65.0, 64.0, 66.0, 67.0, 66.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1499.5000000000002, "alertThreshold": 2.0}]} 05:55:48 INFO - Return code: 0 05:55:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:55:48 ERROR - # TBPL SUCCESS # 05:55:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:55:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:55:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:55:48 INFO - 2016-05-30 05:55:48.857 screenresolution[1677:10515] starting screenresolution argv=screenresolution get 05:55:48 INFO - 2016-05-30 05:55:48.860 screenresolution[1677:10515] Display 0: 1600x1200x32@60 05:55:48 INFO - 2016-05-30 05:55:48.866 screenresolution[1678:10518] starting screenresolution argv=screenresolution list 05:55:48 INFO - Available Modes on Display 0 05:55:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:55:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:55:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:55:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:55:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:55:49 INFO - Intel Iris: 05:55:49 INFO - Chipset Model: Intel Iris 05:55:49 INFO - Type: GPU 05:55:49 INFO - Bus: Built-In 05:55:49 INFO - VRAM (Dynamic, Max): 1536 MB 05:55:49 INFO - Vendor: Intel (0x8086) 05:55:49 INFO - Device ID: 0x0a2e 05:55:49 INFO - Revision ID: 0x0009 05:55:49 INFO - Displays: 05:55:49 INFO - Display: 05:55:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:55:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:55:49 INFO - Display Serial Number: 1600x1200 60 05:55:49 INFO - Main Display: Yes 05:55:49 INFO - Mirror: Off 05:55:49 INFO - Online: Yes 05:55:49 INFO - Rotation: Supported 05:55:49 INFO - Return code: 0 05:55:49 INFO - Running post-action listener: _resource_record_post_action 05:55:49 INFO - ##### 05:55:49 INFO - ##### Finished run-tests step (success) 05:55:49 INFO - ##### 05:55:49 INFO - Running post-run listener: _resource_record_post_run 05:55:49 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31538688; Write bytes: 698673152; Read time: 200; Write time: 1896 05:55:49 INFO - TinderboxPrint: CPU usage
53.5% 05:55:49 INFO - TinderboxPrint: I/O read bytes / time
31,538,688 / 200 05:55:49 INFO - TinderboxPrint: I/O write bytes / time
698,673,152 / 1,896 05:55:49 INFO - TinderboxPrint: CPU idle
2,253.1 (46.5%) 05:55:49 INFO - TinderboxPrint: CPU system
962.4 (19.9%) 05:55:49 INFO - TinderboxPrint: CPU user
1,630.2 (33.6%) 05:55:49 INFO - TinderboxPrint: Swap in / out
425,934,848 / 0 05:55:49 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395983360; Write bytes: 390940672; Read time: 29593; Write time: 790 05:55:49 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29752832; Write bytes: 296480768; Read time: 182; Write time: 1075 05:55:49 INFO - Running post-run listener: _upload_blobber_files 05:55:49 INFO - Blob upload gear active. 05:55:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:55:49 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 05:55:49 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'] 05:55:49 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 05:55:49 INFO - (blobuploader) - INFO - Open directory for files ... 05:55:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:55:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:55:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:51 INFO - (blobuploader) - INFO - Done attempting. 05:55:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:55:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:55:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:52 INFO - (blobuploader) - INFO - Done attempting. 05:55:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:55:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:55:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:55:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:55:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:55:54 INFO - (blobuploader) - INFO - Done attempting. 05:55:54 INFO - (blobuploader) - INFO - Iteration through files over. 05:55:54 INFO - Return code: 0 05:55:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:55:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:55:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0064e7793a1950c34d9250bb639fe4219e42bfd0a6a0a07208760da878365a11448f5a0c84886c011a543f2c8806dacec73dee0e7c78fed878f4a859f3160c0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/700d798bdc48dbd89cca555940f00e6c9047bfb91f8e6782f552c081a27a35191cbcb91fd43e9343d8b57d30d1f4f790cc957a111b1bac14ab4d1750423f18bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f8fcddb3aa1d6d8684e677baa36dd9110c525356a112e7ad029f0eaef9e3578795f9576c080726f82027c3b7cf01e4979368bb0ca99dda5be0246e5d95bb316"} 05:55:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:55:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:55:54 INFO - Contents: 05:55:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0064e7793a1950c34d9250bb639fe4219e42bfd0a6a0a07208760da878365a11448f5a0c84886c011a543f2c8806dacec73dee0e7c78fed878f4a859f3160c0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/700d798bdc48dbd89cca555940f00e6c9047bfb91f8e6782f552c081a27a35191cbcb91fd43e9343d8b57d30d1f4f790cc957a111b1bac14ab4d1750423f18bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f8fcddb3aa1d6d8684e677baa36dd9110c525356a112e7ad029f0eaef9e3578795f9576c080726f82027c3b7cf01e4979368bb0ca99dda5be0246e5d95bb316"} 05:55:54 INFO - Running post-run listener: copy_logs_to_upload_dir 05:55:54 INFO - Copying logs to upload dir... 05:55:54 INFO - mkdir: /builds/slave/test/build/upload/logs 05:55:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1301.558684 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 41 secs) (at 2016-05-30 05:55:54.593260) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:54.596952) ========= 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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners TMPDIR=/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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/0064e7793a1950c34d9250bb639fe4219e42bfd0a6a0a07208760da878365a11448f5a0c84886c011a543f2c8806dacec73dee0e7c78fed878f4a859f3160c0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/700d798bdc48dbd89cca555940f00e6c9047bfb91f8e6782f552c081a27a35191cbcb91fd43e9343d8b57d30d1f4f790cc957a111b1bac14ab4d1750423f18bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f8fcddb3aa1d6d8684e677baa36dd9110c525356a112e7ad029f0eaef9e3578795f9576c080726f82027c3b7cf01e4979368bb0ca99dda5be0246e5d95bb316"} build_url:https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010938 build_url: 'https://queue.taskcluster.net/v1/task/W7JYItwNS5qAuRq7yf9caQ/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/0064e7793a1950c34d9250bb639fe4219e42bfd0a6a0a07208760da878365a11448f5a0c84886c011a543f2c8806dacec73dee0e7c78fed878f4a859f3160c0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/700d798bdc48dbd89cca555940f00e6c9047bfb91f8e6782f552c081a27a35191cbcb91fd43e9343d8b57d30d1f4f790cc957a111b1bac14ab4d1750423f18bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1f8fcddb3aa1d6d8684e677baa36dd9110c525356a112e7ad029f0eaef9e3578795f9576c080726f82027c3b7cf01e4979368bb0ca99dda5be0246e5d95bb316"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:54.627668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:54.628018) ========= 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.GJoAm6r8jZ/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.lFEOjgwzfj/Listeners TMPDIR=/var/folders/0_/xnyh15dn6zsfksvc2py1fy8w00000w/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.005146 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 05:55:54.676566) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:55:54.676886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 05:55:54.677223) ========= ========= Total master_lag: 0.29 =========