builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0271 starttime: 1464372556.71 results: success (0) buildid: 20160527091541 builduid: ea10b0c054ac40e1be19952977d41872 revision: eec76f75c79a0a597131357ec1eed49683183c30 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.712622) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.713052) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.713354) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.749017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.749346) ========= 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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners TMPDIR=/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-27 11:09:16-- 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% 18.8M=0.001s 2016-05-27 11:09:16 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.115458 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.888730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.889120) ========= 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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners TMPDIR=/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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.025216 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.950193) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:16.950536) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eec76f75c79a0a597131357ec1eed49683183c30 --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 eec76f75c79a0a597131357ec1eed49683183c30 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners TMPDIR=/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-27 11:09:17,027 truncating revision to first 12 chars 2016-05-27 11:09:17,027 Setting DEBUG logging. 2016-05-27 11:09:17,027 attempt 1/10 2016-05-27 11:09:17,027 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eec76f75c79a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 11:09:17,701 unpacking tar archive at: fx-team-eec76f75c79a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.940290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:17.914885) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:17.915220) ========= script_repo_revision: eec76f75c79a0a597131357ec1eed49683183c30 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:17.915640) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:17.915964) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 11:09:17.934736) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-27 11:09:17.935053) ========= /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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners TMPDIR=/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:09:18 INFO - MultiFileLogger online at 20160527 11:09:18 in /builds/slave/test 11:09:18 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 11:09:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:09:18 INFO - {'append_to_log': False, 11:09:18 INFO - 'base_work_dir': '/builds/slave/test', 11:09:18 INFO - 'blob_upload_branch': 'Fx-Team', 11:09:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:09:18 INFO - 'branch': 'Fx-Team', 11:09:18 INFO - 'buildbot_json_path': 'buildprops.json', 11:09:18 INFO - 'config_files': ('talos/mac_config.py',), 11:09:18 INFO - 'default_actions': ('clobber', 11:09:18 INFO - 'read-buildbot-config', 11:09:18 INFO - 'download-and-extract', 11:09:18 INFO - 'populate-webroot', 11:09:18 INFO - 'create-virtualenv', 11:09:18 INFO - 'install', 11:09:18 INFO - 'run-tests'), 11:09:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:09:18 INFO - 'download_minidump_stackwalk': True, 11:09:18 INFO - 'download_symbols': 'ondemand', 11:09:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:09:18 INFO - 'tooltool.py': '/tools/tooltool.py', 11:09:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:09:18 INFO - '/tools/misc-python/virtualenv.py')}, 11:09:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:09:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:09:18 INFO - 'installer_path': 'installer.exe', 11:09:18 INFO - 'log_level': 'info', 11:09:18 INFO - 'log_name': 'talos', 11:09:18 INFO - 'log_to_console': True, 11:09:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:09:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:09:18 INFO - 'opt_config_files': (), 11:09:18 INFO - 'pip_index': False, 11:09:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:09:18 INFO - 'cmd': ('bash', 11:09:18 INFO - '-c', 11:09:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:09:18 INFO - 'enabled': True, 11:09:18 INFO - 'halt_on_failure': False, 11:09:18 INFO - 'name': 'check_screen_resolution'},), 11:09:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:09:18 INFO - 'cmd': ('bash', 11:09:18 INFO - '-c', 11:09:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:09:18 INFO - 'enabled': True, 11:09:18 INFO - 'halt_on_failure': False, 11:09:18 INFO - 'name': 'check_screen_resolution'},), 11:09:18 INFO - 'run_cmd_checks_enabled': True, 11:09:18 INFO - 'sps_profile': False, 11:09:18 INFO - 'sps_profile_interval': 0, 11:09:18 INFO - 'suite': 'dromaeojs', 11:09:18 INFO - 'system_bits': '32', 11:09:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:09:18 INFO - 'title': 't-yosemite-r7-0271', 11:09:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:09:18 INFO - 'use_talos_json': True, 11:09:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:09:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:09:18 INFO - 'work_dir': 'build'} 11:09:18 INFO - ##### 11:09:18 INFO - ##### Running clobber step. 11:09:18 INFO - ##### 11:09:18 INFO - Running pre-action listener: _resource_record_pre_action 11:09:18 INFO - Running main action method: clobber 11:09:18 INFO - rmtree: /builds/slave/test/build 11:09:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:09:19 INFO - Running post-action listener: _resource_record_post_action 11:09:19 INFO - ##### 11:09:19 INFO - ##### Finished clobber step (success) 11:09:19 INFO - ##### 11:09:19 INFO - ##### 11:09:19 INFO - ##### Running read-buildbot-config step. 11:09:19 INFO - ##### 11:09:19 INFO - Running pre-action listener: _resource_record_pre_action 11:09:19 INFO - Running main action method: read_buildbot_config 11:09:19 INFO - Using buildbot properties: 11:09:19 INFO - { 11:09:19 INFO - "project": "", 11:09:19 INFO - "product": "firefox", 11:09:19 INFO - "script_repo_revision": "production", 11:09:19 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:09:19 INFO - "repository": "", 11:09:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:09:19 INFO - "buildid": "20160527091541", 11:09:19 INFO - "pgo_build": "False", 11:09:19 INFO - "basedir": "/builds/slave/test", 11:09:19 INFO - "buildnumber": 41, 11:09:19 INFO - "slavename": "t-yosemite-r7-0271", 11:09:19 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:09:19 INFO - "platform": "yosemite_r7", 11:09:19 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:09:19 INFO - "branch": "fx-team", 11:09:19 INFO - "repo_path": "integration/fx-team", 11:09:19 INFO - "revision": "eec76f75c79a0a597131357ec1eed49683183c30", 11:09:19 INFO - "stage_platform": "macosx64", 11:09:19 INFO - "builduid": "ea10b0c054ac40e1be19952977d41872", 11:09:19 INFO - "slavebuilddir": "test" 11:09:19 INFO - } 11:09:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:09:19 INFO - Running post-action listener: _resource_record_post_action 11:09:19 INFO - ##### 11:09:19 INFO - ##### Finished read-buildbot-config step (success) 11:09:19 INFO - ##### 11:09:19 INFO - ##### 11:09:19 INFO - ##### Running download-and-extract step. 11:09:19 INFO - ##### 11:09:19 INFO - Running pre-action listener: _resource_record_pre_action 11:09:19 INFO - Running main action method: download_and_extract 11:09:19 INFO - mkdir: /builds/slave/test/build/tests 11:09: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')]} 11:09:19 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:20 INFO - Downloaded 1199 bytes. 11:09:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:09:20 INFO - Using the following test package requirements: 11:09:20 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:09:20 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:09:20 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:09:20 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'jsshell-mac.zip'], 11:09:20 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:09:20 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:09:20 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:09:20 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:09:20 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:09:20 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:09:20 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:09:20 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:20 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:09:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:09:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:09:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:22 INFO - Downloaded 18684825 bytes. 11:09:22 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'] 11:09:22 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 11:09:25 INFO - Return code: 0 11:09:25 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 11:09:25 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')]} 11:09:25 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:09:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:09:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:09:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:27 INFO - Downloaded 18684825 bytes. 11:09:27 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'] 11:09:27 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 11:09:30 INFO - Return code: 0 11:09:30 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')]} 11:09:30 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:09:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:09:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:09:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:33 INFO - Downloaded 10834034 bytes. 11:09:33 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'] 11:09:33 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 11:09:33 INFO - Return code: 0 11:09: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')]} 11:09:33 INFO - https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:09:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:09:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:09:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:09:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VAblgGN6RKWdxpVP1mog-w/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 11:09:37 INFO - Downloaded 110486912 bytes. 11:09:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:09:37 INFO - mkdir: /builds/slave/test/properties 11:09:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:09:37 INFO - Writing to file /builds/slave/test/properties/build_url 11:09:37 INFO - Contents: 11:09:37 INFO - build_url:https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:09:38 INFO - Running post-action listener: _resource_record_post_action 11:09:38 INFO - Running post-action listener: set_extra_try_arguments 11:09:38 INFO - ##### 11:09:38 INFO - ##### Finished download-and-extract step (success) 11:09:38 INFO - ##### 11:09:38 INFO - ##### 11:09:38 INFO - ##### Running populate-webroot step. 11:09:38 INFO - ##### 11:09:38 INFO - Running pre-action listener: _resource_record_pre_action 11:09:38 INFO - Running main action method: populate_webroot 11:09:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:09:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:09:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:09:38 INFO - '--tppagecycles', 11:09:38 INFO - '7'], 11:09:38 INFO - 'tests': ['tsvgm']}}, 11:09:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:09:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:09:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:09:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:09:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['damp', 'tps']}, 11:09:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'tests': ['damp', 'tps']}, 11:09:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['dromaeo_dom']}, 11:09:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:09:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['a11yr', 11:09:38 INFO - 'ts_paint', 11:09:38 INFO - 'tpaint', 11:09:38 INFO - 'sessionrestore', 11:09:38 INFO - 'sessionrestore_no_auto_restore', 11:09:38 INFO - 'tabpaint']}, 11:09:38 INFO - 'other-e10s': {'tests': ['a11yr', 11:09:38 INFO - 'ts_paint', 11:09:38 INFO - 'tpaint', 11:09:38 INFO - 'sessionrestore', 11:09:38 INFO - 'sessionrestore_no_auto_restore', 11:09:38 INFO - 'tabpaint']}, 11:09:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:09:38 INFO - 'ts_paint', 11:09:38 INFO - 'tpaint', 11:09:38 INFO - 'sessionrestore', 11:09:38 INFO - 'sessionrestore_no_auto_restore', 11:09:38 INFO - 'tabpaint']}, 11:09:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:09:38 INFO - 'ts_paint', 11:09:38 INFO - 'tpaint', 11:09:38 INFO - 'sessionrestore', 11:09:38 INFO - 'sessionrestore_no_auto_restore', 11:09:38 INFO - 'tabpaint']}, 11:09:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['a11yr', 11:09:38 INFO - 'ts_paint', 11:09:38 INFO - 'tpaint', 11:09:38 INFO - 'sessionrestore', 11:09:38 INFO - 'sessionrestore_no_auto_restore', 11:09:38 INFO - 'tabpaint']}, 11:09:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['a11yr', 11:09:38 INFO - 'ts_paint', 11:09:38 INFO - 'tpaint', 11:09:38 INFO - 'sessionrestore', 11:09:38 INFO - 'sessionrestore_no_auto_restore', 11:09:38 INFO - 'tabpaint']}, 11:09:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['tsvgx', 11:09:38 INFO - 'tsvgr_opacity', 11:09:38 INFO - 'tart', 11:09:38 INFO - 'tscrollx', 11:09:38 INFO - 'cart']}, 11:09:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:09:38 INFO - 'tsvgr_opacity', 11:09:38 INFO - 'tart', 11:09:38 INFO - 'tscrollx', 11:09:38 INFO - 'cart']}, 11:09:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'talos_options': ['--disable-e10s'], 11:09:38 INFO - 'tests': ['tp5o']}, 11:09:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'tests': ['tp5o']}, 11:09:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'talos_options': ['--disable-e10s', 11:09:38 INFO - '--xperf_path', 11:09:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:38 INFO - 'tests': ['tp5n']}, 11:09:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:38 INFO - 'talos_options': ['--xperf_path', 11:09:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:38 INFO - 'tests': ['tp5n']}}, 11:09:38 INFO - 'talos.zip': {'path': '', 11:09:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:09:38 INFO - Running post-action listener: _resource_record_post_action 11:09:38 INFO - ##### 11:09:38 INFO - ##### Finished populate-webroot step (success) 11:09:38 INFO - ##### 11:09:38 INFO - ##### 11:09:38 INFO - ##### Running create-virtualenv step. 11:09:38 INFO - ##### 11:09:38 INFO - Running pre-action listener: _resource_record_pre_action 11:09:38 INFO - Running main action method: create_virtualenv 11:09:38 INFO - Creating virtualenv /builds/slave/test/build/venv 11:09:38 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 11:09:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:09:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:09:38 INFO - Using real prefix '/tools/python27' 11:09:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:09:38 INFO - Installing distribute.............................................................................................................................................................................................done. 11:09:41 INFO - Installing pip.................done. 11:09:41 INFO - Return code: 0 11:09:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:09:41 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')]} 11:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:41 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')]} 11:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:41 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 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:09:41 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 11:09:41 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 11:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:41 INFO - 'HOME': '/Users/cltbld', 11:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:41 INFO - 'LOGNAME': 'cltbld', 11:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:41 INFO - 'MOZ_NO_REMOTE': '1', 11:09:41 INFO - 'NO_EM_RESTART': '1', 11:09:41 INFO - 'PAGER': '/bin/cat', 11:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:41 INFO - 'PWD': '/builds/slave/test', 11:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:41 INFO - 'SHELL': '/bin/bash', 11:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:09:41 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:41 INFO - 'USER': 'cltbld', 11:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:41 INFO - 'XPC_FLAGS': '0x0', 11:09:41 INFO - 'XPC_SERVICE_NAME': '0', 11:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:42 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 11:09:42 INFO - Cleaning up... 11:09:42 INFO - Return code: 0 11:09:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:09:42 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')]} 11:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:42 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')]} 11:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:42 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 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:09:42 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 11:09:42 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 11:09:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:09:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:42 INFO - 'HOME': '/Users/cltbld', 11:09:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:42 INFO - 'LOGNAME': 'cltbld', 11:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:42 INFO - 'MOZ_NO_REMOTE': '1', 11:09:42 INFO - 'NO_EM_RESTART': '1', 11:09:42 INFO - 'PAGER': '/bin/cat', 11:09:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:42 INFO - 'PWD': '/builds/slave/test', 11:09:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:42 INFO - 'SHELL': '/bin/bash', 11:09:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:09:42 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:09:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:42 INFO - 'USER': 'cltbld', 11:09:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:42 INFO - 'XPC_FLAGS': '0x0', 11:09:42 INFO - 'XPC_SERVICE_NAME': '0', 11:09:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:42 INFO - Downloading/unpacking psutil>=3.1.1 11:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:09:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:45 INFO - Installing collected packages: psutil 11:09:45 INFO - Running setup.py install for psutil 11:09:45 INFO - building 'psutil._psutil_osx' extension 11:09:45 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 11:09:45 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 11:09:45 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 11:09:46 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 11:09:46 INFO - building 'psutil._psutil_posix' extension 11:09:46 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 11:09:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:09:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:09:46 INFO - ^ 11:09:46 INFO - 1 warning generated. 11:09:46 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 11:09:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:46 INFO - Successfully installed psutil 11:09:46 INFO - Cleaning up... 11:09:46 INFO - Return code: 0 11:09:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:09:46 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')]} 11:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:46 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')]} 11:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:46 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 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:09:46 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 11:09:46 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 11:09:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:09:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:46 INFO - 'HOME': '/Users/cltbld', 11:09:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:46 INFO - 'LOGNAME': 'cltbld', 11:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:46 INFO - 'MOZ_NO_REMOTE': '1', 11:09:46 INFO - 'NO_EM_RESTART': '1', 11:09:46 INFO - 'PAGER': '/bin/cat', 11:09:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:46 INFO - 'PWD': '/builds/slave/test', 11:09:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:46 INFO - 'SHELL': '/bin/bash', 11:09:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:09:46 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:09:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:46 INFO - 'USER': 'cltbld', 11:09:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:46 INFO - 'XPC_FLAGS': '0x0', 11:09:46 INFO - 'XPC_SERVICE_NAME': '0', 11:09:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:09:46 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 11:09:46 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 11:09:46 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 11:09:46 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 11:09:46 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 11:09:46 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 11:09:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:09:49 INFO - Installing collected packages: mozsystemmonitor 11:09:49 INFO - Running setup.py install for mozsystemmonitor 11:09:49 INFO - Successfully installed mozsystemmonitor 11:09:49 INFO - Cleaning up... 11:09:49 INFO - Return code: 0 11:09:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:49 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 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:09:49 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 11:09:49 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 11:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:49 INFO - 'HOME': '/Users/cltbld', 11:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:49 INFO - 'LOGNAME': 'cltbld', 11:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:49 INFO - 'MOZ_NO_REMOTE': '1', 11:09:49 INFO - 'NO_EM_RESTART': '1', 11:09:49 INFO - 'PAGER': '/bin/cat', 11:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:49 INFO - 'PWD': '/builds/slave/test', 11:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:49 INFO - 'SHELL': '/bin/bash', 11:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:09:49 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:49 INFO - 'USER': 'cltbld', 11:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:49 INFO - 'XPC_FLAGS': '0x0', 11:09:49 INFO - 'XPC_SERVICE_NAME': '0', 11:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:49 INFO - Downloading/unpacking blobuploader==1.2.4 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 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 11:09:49 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 11:09:52 INFO - Downloading blobuploader-1.2.4.tar.gz 11:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:09:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:09:52 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 11:09:52 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 11:09:52 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 11:09:52 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 11:09:52 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 11:09:52 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 11:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:09:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:53 INFO - Downloading docopt-0.6.1.tar.gz 11:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:09:53 INFO - Installing collected packages: blobuploader, requests, docopt 11:09:53 INFO - Running setup.py install for blobuploader 11:09:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:09:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:09:53 INFO - Running setup.py install for requests 11:09:54 INFO - Running setup.py install for docopt 11:09:54 INFO - Successfully installed blobuploader requests docopt 11:09:54 INFO - Cleaning up... 11:09:54 INFO - Return code: 0 11:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:54 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 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:09:54 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 11:09:54 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 11:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:54 INFO - 'HOME': '/Users/cltbld', 11:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:54 INFO - 'LOGNAME': 'cltbld', 11:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:54 INFO - 'MOZ_NO_REMOTE': '1', 11:09:54 INFO - 'NO_EM_RESTART': '1', 11:09:54 INFO - 'PAGER': '/bin/cat', 11:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:54 INFO - 'PWD': '/builds/slave/test', 11:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:54 INFO - 'SHELL': '/bin/bash', 11:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:09:54 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:54 INFO - 'USER': 'cltbld', 11:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:54 INFO - 'XPC_FLAGS': '0x0', 11:09:54 INFO - 'XPC_SERVICE_NAME': '0', 11:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:54 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-v61Tc2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:54 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-nWZKTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:54 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-XWPBSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:54 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-fF78B0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-6uviki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-3B3paT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-SdyzD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-SprlHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-miPDrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-Fc_hYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-q77gWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-GsC97u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-nchlV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:55 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-EB9UMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:56 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-u3MIXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:56 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-PBacXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:56 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-01yKl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:09:56 INFO - Running setup.py install for manifestparser 11:09:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:09:56 INFO - Running setup.py install for mozcrash 11:09:56 INFO - Running setup.py install for mozdebug 11:09:56 INFO - Running setup.py install for mozdevice 11:09:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:09:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:09:56 INFO - Running setup.py install for mozfile 11:09:57 INFO - Running setup.py install for mozhttpd 11:09:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:09:57 INFO - Running setup.py install for mozinfo 11:09:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:09:57 INFO - Running setup.py install for mozInstall 11:09:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:09:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:09:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:09:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:09:57 INFO - Running setup.py install for mozleak 11:09:57 INFO - Running setup.py install for mozlog 11:09:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:09:57 INFO - Running setup.py install for moznetwork 11:09:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:09:58 INFO - Running setup.py install for mozprocess 11:09:58 INFO - Running setup.py install for mozprofile 11:09:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:09:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:09:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:09:58 INFO - Running setup.py install for mozrunner 11:09:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:09:58 INFO - Running setup.py install for mozscreenshot 11:09:58 INFO - Running setup.py install for moztest 11:09:58 INFO - Running setup.py install for mozversion 11:09:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:09:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:09:58 INFO - Cleaning up... 11:09:58 INFO - Return code: 0 11:09:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:58 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 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:09:58 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 11:09:58 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 11:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:58 INFO - 'HOME': '/Users/cltbld', 11:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:58 INFO - 'LOGNAME': 'cltbld', 11:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:58 INFO - 'MOZ_NO_REMOTE': '1', 11:09:58 INFO - 'NO_EM_RESTART': '1', 11:09:58 INFO - 'PAGER': '/bin/cat', 11:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:58 INFO - 'PWD': '/builds/slave/test', 11:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:58 INFO - 'SHELL': '/bin/bash', 11:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:09:58 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:58 INFO - 'USER': 'cltbld', 11:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:58 INFO - 'XPC_FLAGS': '0x0', 11:09:58 INFO - 'XPC_SERVICE_NAME': '0', 11:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-4sRJxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:59 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)) 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-VL0MQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:59 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)) 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-z6HlE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:59 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)) 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-dPSDNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:59 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)) 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-g0Kacy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:59 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)) 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-uliAx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:59 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)) 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-6OV2JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:59 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)) 11:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:59 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-03WfT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-_iD_uq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-wkvwyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-qiLleV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-zzbgyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-7TL1tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-gBPEX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-sqEjvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-oU4JxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:10:00 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)) 11:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:10:00 INFO - Running setup.py (path:/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/pip-5uIE1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:10:00 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)) 11:10:00 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)) 11:10:00 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)) 11:10:00 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)) 11:10:00 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)) 11:10:00 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)) 11:10:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:03 INFO - Downloading blessings-1.6.tar.gz 11:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:10:03 INFO - Installing collected packages: blessings 11:10:03 INFO - Running setup.py install for blessings 11:10:03 INFO - Successfully installed blessings 11:10:03 INFO - Cleaning up... 11:10:03 INFO - Return code: 0 11:10:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:10:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:04 INFO - Reading from file tmpfile_stdout 11:10:04 INFO - Current package versions: 11:10:04 INFO - blessings == 1.6 11:10:04 INFO - blobuploader == 1.2.4 11:10:04 INFO - docopt == 0.6.1 11:10:04 INFO - manifestparser == 1.1 11:10:04 INFO - mozInstall == 1.12 11:10:04 INFO - mozcrash == 0.17 11:10:04 INFO - mozdebug == 0.1 11:10:04 INFO - mozdevice == 0.48 11:10:04 INFO - mozfile == 1.2 11:10:04 INFO - mozhttpd == 0.7 11:10:04 INFO - mozinfo == 0.9 11:10:04 INFO - mozleak == 0.1 11:10:04 INFO - mozlog == 3.2 11:10:04 INFO - moznetwork == 0.27 11:10:04 INFO - mozprocess == 0.22 11:10:04 INFO - mozprofile == 0.28 11:10:04 INFO - mozrunner == 6.11 11:10:04 INFO - mozscreenshot == 0.1 11:10:04 INFO - mozsystemmonitor == 0.3 11:10:04 INFO - moztest == 0.7 11:10:04 INFO - mozversion == 1.4 11:10:04 INFO - psutil == 3.1.1 11:10:04 INFO - requests == 1.2.3 11:10:04 INFO - wsgiref == 0.1.2 11:10:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:04 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 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:10:04 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 11:10:04 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 11:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:04 INFO - 'HOME': '/Users/cltbld', 11:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:04 INFO - 'LOGNAME': 'cltbld', 11:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:04 INFO - 'MOZ_NO_REMOTE': '1', 11:10:04 INFO - 'NO_EM_RESTART': '1', 11:10:04 INFO - 'PAGER': '/bin/cat', 11:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:04 INFO - 'PWD': '/builds/slave/test', 11:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:04 INFO - 'SHELL': '/bin/bash', 11:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:10:04 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:04 INFO - 'USER': 'cltbld', 11:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:04 INFO - 'XPC_FLAGS': '0x0', 11:10:04 INFO - 'XPC_SERVICE_NAME': '0', 11:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 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)) 11:10:04 INFO - Cleaning up... 11:10:04 INFO - Return code: 0 11:10:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:04 INFO - 'HOME': '/Users/cltbld', 11:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:04 INFO - 'LOGNAME': 'cltbld', 11:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:04 INFO - 'MOZ_NO_REMOTE': '1', 11:10:04 INFO - 'NO_EM_RESTART': '1', 11:10:04 INFO - 'PAGER': '/bin/cat', 11:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:04 INFO - 'PWD': '/builds/slave/test', 11:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:04 INFO - 'SHELL': '/bin/bash', 11:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:10:04 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:04 INFO - 'USER': 'cltbld', 11:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:04 INFO - 'XPC_FLAGS': '0x0', 11:10:04 INFO - 'XPC_SERVICE_NAME': '0', 11:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:04 INFO - Downloading/unpacking jsonschema 11:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:10:07 INFO - Downloading/unpacking functools32 (from jsonschema) 11:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:07 INFO - Downloading functools32-3.2.3-2.tar.gz 11:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:10:07 INFO - warning: no files found matching '*.txt' 11:10:07 INFO - no previously-included directories found matching 'build' 11:10:07 INFO - no previously-included directories found matching 'dist' 11:10:07 INFO - no previously-included directories found matching '.git*' 11:10:07 INFO - Installing collected packages: jsonschema, functools32 11:10:07 INFO - Running setup.py install for functools32 11:10:07 INFO - warning: no files found matching '*.txt' 11:10:07 INFO - no previously-included directories found matching 'build' 11:10:07 INFO - no previously-included directories found matching 'dist' 11:10:07 INFO - no previously-included directories found matching '.git*' 11:10:07 INFO - Successfully installed jsonschema functools32 11:10:07 INFO - Cleaning up... 11:10:07 INFO - Return code: 0 11:10:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb0aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb10570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b0159db70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, '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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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 11:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:07 INFO - 'HOME': '/Users/cltbld', 11:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:07 INFO - 'LOGNAME': 'cltbld', 11:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:07 INFO - 'MOZ_NO_REMOTE': '1', 11:10:07 INFO - 'NO_EM_RESTART': '1', 11:10:07 INFO - 'PAGER': '/bin/cat', 11:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:07 INFO - 'PWD': '/builds/slave/test', 11:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:07 INFO - 'SHELL': '/bin/bash', 11:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:10:07 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:07 INFO - 'USER': 'cltbld', 11:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:07 INFO - 'XPC_FLAGS': '0x0', 11:10:07 INFO - 'XPC_SERVICE_NAME': '0', 11:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:08 INFO - Downloading/unpacking flake8 11:10:08 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 11:10:08 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 11:10:08 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 11:10:08 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 11:10:08 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 11:10:08 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 11:10:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:10:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:10: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 11:10: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 11:10: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 11:10: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 11:10: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 11:10: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 11:10:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:10:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:10:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:10:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:10:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:10:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:10:11 INFO - no previously-included directories found matching 'docs/_build' 11:10:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:10:12 INFO - Running setup.py install for pep8 11:10:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:10:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:10:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:10:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:10:12 INFO - no previously-included directories found matching 'docs/_build' 11:10:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:10:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:10:12 INFO - Cleaning up... 11:10:12 INFO - Return code: 0 11:10:12 INFO - Running post-action listener: _resource_record_post_action 11:10:12 INFO - Running post-action listener: _start_resource_monitoring 11:10:12 INFO - Starting resource monitoring. 11:10:12 INFO - ##### 11:10:12 INFO - ##### Finished create-virtualenv step (success) 11:10:12 INFO - ##### 11:10:12 INFO - ##### 11:10:12 INFO - ##### Running install step. 11:10:12 INFO - ##### 11:10:12 INFO - Running pre-action listener: _resource_record_pre_action 11:10:12 INFO - Running main action method: install 11:10:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:12 INFO - Reading from file tmpfile_stdout 11:10:12 INFO - Detecting whether we're running mozinstall >=1.0... 11:10:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:10:12 INFO - Reading from file tmpfile_stdout 11:10:12 INFO - Output received: 11:10:12 INFO - Usage: mozinstall [options] installer 11:10:12 INFO - Options: 11:10:12 INFO - -h, --help show this help message and exit 11:10:12 INFO - -d DEST, --destination=DEST 11:10:12 INFO - Directory to install application into. [default: 11:10:12 INFO - "/builds/slave/test"] 11:10:12 INFO - --app=APP Application being installed. [default: firefox] 11:10:12 INFO - mkdir: /builds/slave/test/build/application 11:10:12 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'] 11:10:12 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 11:10:46 INFO - Reading from file tmpfile_stdout 11:10:46 INFO - Output received: 11:10:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:10:46 INFO - Running post-action listener: _resource_record_post_action 11:10:46 INFO - ##### 11:10:46 INFO - ##### Finished install step (success) 11:10:46 INFO - ##### 11:10:46 INFO - ##### 11:10:46 INFO - ##### Running run-tests step. 11:10:46 INFO - ##### 11:10:46 INFO - Running pre-action listener: _resource_record_pre_action 11:10:46 INFO - Running main action method: run_tests 11:10:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:10:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:10:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:10:46 INFO - 2016-05-27 11:10:46.580 screenresolution[1638:7456] starting screenresolution argv=screenresolution get 11:10:46 INFO - 2016-05-27 11:10:46.583 screenresolution[1638:7456] Display 0: 1600x1200x32@60 11:10:46 INFO - 2016-05-27 11:10:46.589 screenresolution[1639:7458] starting screenresolution argv=screenresolution list 11:10:46 INFO - Available Modes on Display 0 11:10:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:10:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:10:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:10:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:10:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:10:46 INFO - Intel Iris: 11:10:46 INFO - Chipset Model: Intel Iris 11:10:46 INFO - Type: GPU 11:10:46 INFO - Bus: Built-In 11:10:46 INFO - VRAM (Dynamic, Max): 1536 MB 11:10:46 INFO - Vendor: Intel (0x8086) 11:10:46 INFO - Device ID: 0x0a2e 11:10:46 INFO - Revision ID: 0x0009 11:10:46 INFO - Displays: 11:10:46 INFO - Display: 11:10:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:10:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:10:46 INFO - Display Serial Number: 1600x1200 60 11:10:46 INFO - Main Display: Yes 11:10:46 INFO - Mirror: Off 11:10:46 INFO - Online: Yes 11:10:46 INFO - Rotation: Supported 11:10:46 INFO - Return code: 0 11:10:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:10:46 INFO - Python 2.7.3 11:10:46 INFO - Return code: 0 11:10:46 INFO - grabbing minidump binary from tooltool 11:10:46 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')]} 11:10:46 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 0x10c9aed30>, '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 0x10c7d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:10:46 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 11:10:46 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 11:10:46 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 11:10:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:10:46 INFO - Return code: 0 11:10:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:10:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:10:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:10:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:10:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:10:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:10:48 INFO - Return code: 0 11:10:48 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/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0271', '--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 11:10:48 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/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0271 --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 11:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BjiH1MopGh/Render', 11:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:48 INFO - 'HOME': '/Users/cltbld', 11:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:48 INFO - 'LOGNAME': 'cltbld', 11:10:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:10:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:48 INFO - 'MOZ_NO_REMOTE': '1', 11:10:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:10:48 INFO - 'NO_EM_RESTART': '1', 11:10:48 INFO - 'PAGER': '/bin/cat', 11:10:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:48 INFO - 'PWD': '/builds/slave/test', 11:10:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:48 INFO - 'SHELL': '/bin/bash', 11:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kJsLMQ9ZJr/Listeners', 11:10:48 INFO - 'TMPDIR': '/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/', 11:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:48 INFO - 'USER': 'cltbld', 11:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:48 INFO - 'XPC_FLAGS': '0x0', 11:10:48 INFO - 'XPC_SERVICE_NAME': '0', 11:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:48 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/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0271', '--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 11:10:48 INFO - mozversion application_buildid: 20160527091541 11:10:48 INFO - mozversion application_changeset: eec76f75c79a0a597131357ec1eed49683183c30 11:10:48 INFO - mozversion application_display_name: Nightly 11:10:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:10:48 INFO - mozversion application_name: Firefox 11:10:48 INFO - mozversion application_remotingname: firefox 11:10:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:10:48 INFO - mozversion application_vendor: Mozilla 11:10:48 INFO - mozversion application_version: 49.0a1 11:10:48 INFO - mozversion platform_buildid: 20160527091541 11:10:48 INFO - mozversion platform_changeset: eec76f75c79a0a597131357ec1eed49683183c30 11:10:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:10:48 INFO - mozversion platform_version: 49.0a1 11:10:48 INFO - using testdate: 1464372648 11:10:48 INFO - actual date: 1464372648 11:10:48 INFO - starting webserver on 'localhost:49270' 11:10:48 INFO - SUITE-START | Running 2 tests 11:10:48 INFO - TEST-START | dromaeo_css 11:10:48 INFO - operating with platform_type : mac_ 11:10:48 INFO - Initialising browser for dromaeo_css test... 11:10:48 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/tmpwKMiU_/profile http://localhost:49270/getInfo.html) 11:10:50 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 11:10:50 INFO - PROCESS | 1657 | colorDepth:24 11:10:50 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 11:10:50 INFO - PROCESS | 1657 | __metrics 11:10:50 INFO - PROCESS | 1657 | 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" 11:10:50 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:10:51 INFO - TEST-INFO | 1657: exit 0 11:10:51 INFO - Browser initialized. 11:10:51 INFO - Running cycle 1/1 for dromaeo_css test... 11:10:51 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/tmpwKMiU_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:03 INFO - PROCESS | 1659 | RSS: Main: 241561600 11:11:03 INFO - PROCESS | 1659 | 11:11:55 INFO - PROCESS | 1659 | 1464372715044 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] 11:14:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 11:14:07 INFO - PROCESS | 1659 | RSS: Main: 441966592 11:14:07 INFO - PROCESS | 1659 | 11:17:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 11:17:11 INFO - PROCESS | 1659 | RSS: Main: 381923328 11:17:11 INFO - PROCESS | 1659 | 11:20:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 11:20:13 INFO - PROCESS | 1659 | RSS: Main: 376094720 11:20:13 INFO - PROCESS | 1659 | 11:23:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 11:23:16 INFO - PROCESS | 1659 | RSS: Main: 374231040 11:23:16 INFO - PROCESS | 1659 | 11:26:20 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 11:26:20 INFO - PROCESS | 1659 | RSS: Main: 350502912 11:26:20 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 11:29:24 INFO - PROCESS | 1659 | RSS: Main: 382181376 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | __start_tp_report 11:29:24 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 11:29:24 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 11:29:24 INFO - PROCESS | 1659 | |i|pagename|runs| 11:29:24 INFO - PROCESS | 1659 | |0;dojo.html;3436.7422443316755;3463.6016858061316;3467.0465621953967;3476.1309672581856;3537.1334023164313;5581.5255703265275;5599.608027438081;5661.23573317602;5705.315362156537;5718.142278658198;5054.519820617035;5432.456754324566;5713.428657134285;5719.256496655429;5735.0250457422235;5158.561522270602;5214.843554693346;5228.372595288571;5235.371755389339;5291.841244762663;4398.472183338004;5248.816291429782;5280.049591073605;5287.735613219343;5292.073887069767;5167.069927413064;5178.275041494194;5207.062728708831;5215.6349055566125;5294.92057619136;5273.945210957804;5298.92051619226;5299.231611416361;5305.628605997582;5349.812756553541;5113.309703190096;5131.974340128276;5135.666181698178;5244.292020577212;5253.50091741284;5533.778648854041;5576.553875689935;5580.441955804427;5590.636608620467;5614.9157762633595;6207.0379091240475;6256.71844766982;6263.404976527223;6270;6281.089242059876;6134.60125091872;6229.25248970122;6258.624482531017;6264.561480696353;6295.653739044351;5900.734466948998;5908.468237858613;5925.259342582177;5926.555508336892;5930.55520835939;6269.279032911274;6286.308506064329;6293.1504246927;6302.212223472066;6304.968475157595;5350.384705758887;5847.824565262964;6122.173506576555;6138.296437970319;6174;6186.845328866814;6187.876242475215;6192.040233763774;6199.225096862892;6207.751689932443;6134.8159555213415;6162.691865406801;6167.599106058092;6172.321044685081;6196.10156527301;6108.736706092798;6114.449699527064;6122.12371750169;6134.834601026089;6156.6921653918025;4980.9252861207115;4984.352034235526;4992.375953005874;4998.375203099613;5035.823746168867;4409;4415.889602759957;4418.7127836690515;4439.445069366329;4444.2222611042935;5646.774129034875;5694.402087780723;5832.008558545058;5843.152742852262;5881.235439392937;6106.885399019288;6140.662263575547;6148.739508400767;6161.537884658668;6166.445019948226;5760;5782.508486778587;5786.768529258867;5809.854753631109;5811.041178205549;5364.436734142937;5427.83716488506;5431.646942948696;5432.2123292123215;5443.319585051869;6155.10750941111;6249.750009999549;6283.654399008098;6298.401651843067;6381.776637817654;6155.692215389123;6164.537659675452;6172.9074063889975;6193.38066193377;6194.101855230876;6160.44555989963;6307.842303942254;6334.398232167937;6375.266844312963;6383.744650213939;6104.32852386246;6118.710550942845;6118.832384214075;6162.476189523762;6180.783672571439;6152.323244443018;6181.443670069715;6193.783217587363;6194.845128871814;6199.752009919553;5989.64062156272;6039.275286965417;6100.298465676504;6116.113163591165;6117.296510901303;6055.7577696893395;6089.48239399656;6100.267967843887;6112.083187521907;6113.23584551931;5852.209951656642;5866.120081987565;5951.702414879325;5963.373845746134;5964.194833697396;6061.272647282354;6099.146119543356;6119.908201377072;6124.081387791867;6140.0134868502855;5798.072308430631;5874.500667443315;5947.137665038632;5990.580659353804;6005.789797357072 11:29:24 INFO - PROCESS | 1659 | |1;ext.html;502.8835984115263;517.9766910489028;527.6069328350378;530.9681419114855;535.7133933345658;30130.698693013062;42943.57056429435;44122.779386103066;44397;44457;33623.49564756524;38283.85148445551;43024;43449.34825977613;43486.565134348646;8493;9403.670871519498;9613;9615.423074615546;9626.422414655108;8564.828703425928;8620.353473489513;8642.783930401727;8644.567771611426;11339.886601133965;11309.151813613971;11586.59446919358;11621.418929053514;11638.243514171552;11648.126390520745;11587.42062896856;11706;11706.941465292704;11707;11714.941425292904;8473.067962524117;8704.173103555153;8706.956465217634;8800.823983520293;11454.083673306115;39187.608123918966;39944;40340.991475213355;42296.61627069827;42389.78805105955;3468.3410152071024;3499.230169362736;3508.475166543108;3638.2541578976247;3861.498005259298;31135;31734;31935.520967185512;32165.517517237262;32618.673813262038;2789.8840463814436;3193.050711695075;3273.6071671399345;3294.7858389204625;3298.307355455357;505695;506982;507176;508446;508634;61360.69319653462;61646.691766540884;62143.68928155421;62221;62226.68886655538;63278;63434.365656342845;63436.365636343966;63518.72962540816;63523;16068.919655401649;16641.583960401087;16647.83352166487;16672.916635416746;16703;12877;12905.935470322589;12915.354232288535;12936.741265174644;12949.482020719253;13704.862951370358;14691.265436728121;14971.475998340005;16356.918215408847;16669.91665041667;6422.472157129213;6503.544751867324;6615.966920165369;6663.666816659148;6677.131972843499;771.4908160613968;959.2709540749081;1051.8790339111101;1077.1705786544317;1169.502961241469;30200.69799301979;30572.23569410738;30880.38239235203;31063.844680776452;31095.378092438026;935.8582529314226;1102.652664410708;1105.5909313554037;1107.8781334053247;1173.6889724223088;16286.592835178979;16896.57758556046;16904.492865214062;16970;16979.405720799714;666999;670457;671841.6407917929;672667;675249;59267.70366148141;60955.39044609497;61083;61188;61194;1296.5267677297907;1343.8454577723687;1415.5611760354323;1428.9642758930902;1453.0337325678518;29781.702182977893;30287.68011168153;30432;31146.065618031495;31614.893478728132;47186.29220561763;47219.52780472151;47658.761706191246;47754.28368574405;47823.28265075957;525151.4969700661;549747;550737;552247;552508;24183.879080604485;24362.87818560896;24633.507329852942;26463.190336531137;26785.866070669523;37055.25889482141;39389;39592.40611390774;39873.40189897096;47132.7643361781;35182.29635407329;37950.62049379581;39375.21249574935;39486;40308;37872;38560;38773.41839872461;39308;39503.20993580055;38301.61698382981;38777.2244555113;38985.8050709756;39071.80464097661;39101.60898391093;25065.373365666004;33989.32021359509;34575.481367779;34692.30615387628;34709.65290347064;25265.87367063153;25430.618540721534;25472.108476203244;25693.3576660585;26233.606495902193 11:29:24 INFO - PROCESS | 1659 | |2;jquery.html;378.4871499118695;380.34390676083757;386.22561763663833;396.6885994494323;405.50933370621544;19400.22399104036;21289.935503224817;21419.892900535513;21561.029753661085;21579;20228.29200977966;20282.391528254175;21174.682379764316;21186.47033824155;21218.151273949024;5183.974080129613;5855;6011.639301641893;6027.126066720323;6040.607360521574;5866.266716660418;5895.911561326584;5958.940410595882;6005.069214271794;6013.6993150342305;4762.666613337068;5879.265091863517;5883.058710606304;5916.053431450969;5922.2005029321135;5758.74085666146;5898.705064746767;5913.526917846562;5918.733656985403;5923.259592550931;4998.750312421895;5121.129408000649;5162.5095615916425;5170.276161337416;5178;25439;25684.357891052874;25867.09465168729;26271;26454.206373808818;2525.9242222733346;2638.20853743878;2810.2412348665744;2843.1612674260937;2871.1530098620956;9550.718293604965;10297.279190456671;15230.390784368612;15453.304601292968;15559.22203889801;1806.0879255975779;1814.9818501814946;1862.975363550042;1893.3657224829699;1910.9713354299697;114122;114398.42800786026;115098;115345.4232728831;119762;11296.096312294996;11434.371109588887;11487.597934072268;11524.193306468467;11671.241369311138;14428.422863085569;14574;14600.634984125269;14627.78058329126;14628.341724622418;8361.916380836114;8476.915230847613;8525.44584601999;8612.009618893782;8613.353998450142;8301.211384918422;8313.875291870627;8338.04112527055;8432.198941100585;8433.704820331259;3775.8300876460626;4395.428594282723;4489.416375871181;4495.910081798411;4576.4508259009135;1664.334266293468;1844.981550184481;1891.2056936086876;1893.4414347767442;1926.9325573604856;715.8601243074773;957.8563215517728;1001.7245257554085;1064.1167830700654;1080.7298175456135;20004.890439191346;20454.38636840897;20602.58794824125;20648;20695.8965205173;873.6723728601775;898.5462341517492;1008.324422636835;1023.0945613132432;1034.534459493231;10419.166466682647;10731.624393146358;11043.613473528389;11047.392393418439;11057.009738247032;539612;541391;548699;549016;554631;72686.54626907432;109078.45460772804;119372.40313798375;124619;125989;935.1256575102301;953.3136141977765;970.9126178643955;971.2327261463365;974.045435473226;15982;18330;18356.449306520826;18451.261949521868;18867.679262451496;48477;48595;49628.007439851724;50551.74724126503;51040.48959510357;66111;72124.19689507804;72377.34831729828;73345.6332718333;73457;7824.9608751955875;7890.447668663139;7993.560354180576;8017.478863873829;8069.919300807152;11607.941960290145;12151.696207594881;12266;12453.06602004835;12520.624381268577;10651;10688.786224275627;10767.838482422927;10772.461376930965;10871.782564348827;9459.763505912408;9512.476813775309;9712.951435242778;10329.96700329961;10571.894281057092;9205.263578913804;10557.683269501927;10709.591656213563;11000.174986876176;11133.886611339125;7785.571793551182;7923.524588524707;7983.441159119038;8010.759677209693;8129.227723366271;9037.412568183046;10810.51352689115;11009.174311926798;11010.889891100986;11056.613018544635 11:29:24 INFO - PROCESS | 1659 | |3;mootools.html;1364.0860623382332;1509.4867744966712;1560.3914473355392;1753.7720096387468;1933;7680.500767450112;8456;8703.303735701145;8728.650853965833;8820.735377938656;8409.957950210255;8695.478271303726;8700.82538857254;8701.129887011295;8746.081661425555;1900.4678689966763;2072.2229164063474;2153.9461513462134;2322.3033090072995;2359.6224604063354;2350.9412264693347;2384.9284521464383;2387.8447900886385;2387.95224095518;2390.3426557696685;2104.515961328895;2304.274153641606;2322.941926451835;2350.0717216699395;2352.5647755165264;2049.9692504612444;2263.864168149916;2294.1855641247366;2335.591271527493;2337.8597284163006;2166.599179151862;2196.417949243452;2342.4612339161918;2361.6575596538405;2367.242482405631;7968.561729104897;8663.913360866436;8683.782905427352;8689.869651955225;8717.956410217908;5160.741962901878;5328.8401347959625;5333.546648534879;5341.973290133564;5342.759575819097;3361.4957756336503;3447.052060683307;3579.4451859961746;3598.4962105305017;3599.766015209003;1081.7187531241855;1101.1576144249655;1105.9944700276528;1114.8662160540787;1120.6806060272866;11798.292102473879;11802.173847830656;11812.822807657978;11821.763564728742;11842.28946263227;4631.606313463326;4655.464621568495;4760.761961901961;4793.784279707352;4809.759512024385;9862.654807081717;9868.457234852152;9869;9873.40759554429;9877;5311.734413279398;5317.388500322435;5318.627696061239;5318.9202161967605;5320.973395133077;4350;4497.460304763449;4634.281686338623;4841;4842.491538388418;3868.7485313454536;3903.6291552302487;4006.639402453793;4037.313656678372;4043.7775922324554;1276.4192292506887;1319.0502837956656;1320.4124164746788;1328.4096012775128;1329.6277042428135;932.69687351611;942.6323733743845;943.9433633981984;945.0643862576001;991.0386924683046;4942.777575009133;5651.689157096317;5689.886202276014;5750.5112065474905;5849.502792262705;928.0811996123789;963.629002833914;978.4422878958925;984.7538115471132;985.0543478260806;3122.8906988255303;3920;3937.665298449664;3991.5808840071954;3998.200359928026;10033.44816035125;10457.581696732046;10558.419286939292;10560.419176945188;10570.048695617432;10120.949395252977;10514.63198788054;10529.789404211873;10533.104686101615;10554.683359499228;8074.394420418492;8105.392095592854;8220.547869867214;8235.464694794819;8251.216134467217;5376;5774.249347584955;5806.2451881255165;5825.650460972355;5879.441453061952;9746.610135594781;9914.30599858003;9923.503824808875;9923.553440095357;9938.403695778277;9920.759905011873;9962.402255864672;9963;10190;10213.897861021593;6457.61254324742;6649.46804255668;6655.73377064912;6933.064036355027;7086.964565177141;7815.335696465864;7920.287174154354;7958.761237162895;8213.425060245725;8219.630116644885;7213.855722885408;7309.963450182928;7332.890006649798;7425;7508.587027713528;7276.563406195645;7285.562866228043;7312.853742925218;7320.560766354036;7340.376068034277;6758.493113016445;6803.183617965876;6874.793756187322;6891.173059232924;6899.206591242071;4843.878903027453;4884.340614017062;4913.164761990542;4931.5315045070665;4995.275685025724;4878.609711223165;4885.731284779229;4913;4947.45577986414;5094.9490505095955 11:29:24 INFO - PROCESS | 1659 | |4;prototype.html;299.07585560617684;304.4337532190126;313.54379378005007;317.21172885379826;318.1727508477956;4805.870620404202;5084.211947148188;5098.362704661918;5236.240745091959;5301.674581354661;4241.660667146621;5022.69409953412;5201.193814958678;5223.529882310591;5241.554467870235;3095.5975723155957;3368.949465758016;3581.426971684531;3996.100877302613;4028.738132021424;3468.75124955016;3907.2380885727293;3931.3513270310364;4068.3726509396183;4072.3891416287497;3181.8249996250197;3870.774304774766;3987.342088555385;4039.757614543122;4073.287174744423;3984.8007599619905;4018.7789671568053;4035.3946907963746;4042.3936409538655;4057.2696914555368;3256.4301247281633;3275.9353210206777;3373.4602463605706;3477.0959550516795;3552.6980206682465;4286.76422796746;4840.588549973257;5045.6215783816;5054.873628159326;5107.744612769384;3898.863539776094;4049.339770694031;4291.763952982585;4355.695101342907;4684.344191813149;4741.051789642051;5586.944130558723;5595.062592139119;5595.21666966625;5612.7754889804355;3756.572502449052;3918.4710064141545;3931.941020884689;3933.449317095609;3943.98028009861;5007.298978143026;5038.823641172578;5599.488138202662;5749.88500229993;5789.2763404574425;3433.8626454942023;3970.622790834866;3978.5026871641044;3985.70107241958;3986.4618276532296;3430.3996800559803;4024.852916918664;4044.2922488564973;4048.352263637804;4068.145689405199;3902.926695158854;4088.9386659200136;4093.5087789465456;4218.135282267128;4218.7679677618025;5295.285136506523;5343.305370301836;5569.164625306236;5587.720613969285;5595.328560572676;1185.2829038431796;1337.7057047449546;1374.9862501374857;1376.3256004557695;1390.9721805563831;800.9479383840031;802.2939812964554;822.7042408207097;842.2377748137945;855.8758979947997;3737.6262373762406;3989.2247949662124;4230.576942305806;4375.134287041992;4389.824407023683;4215.978920105441;4522.5929666330185;4545.840810593278;4802.879928001758;4815.881012354553;3857;4080.3471444568727;4082.9387559186634;4199.895002624898;4414.690971632019;2182.8144607708205;2556.642070110186;2736.808423410382;2980.240038790138;2986.731194192533;5234.633575649744;5243.501867322599;5850.5904586678525;5858.853528661817;5994.490468310242;4720.511421220846;5109.948900511022;5186.5332120108615;5601;5783.339663754636;5826.912596310974;5871.5027667944405;5884;5893.705314734246;5933.732982015819;5654.7842213924205;5666.490015898589;5667.829965101054;5670.234518339971;5817.534597232296;5135.794568217229;5625.46558076982;5628.439060227504;5636.436356364331;5652.349979752381;5541.783286685197;5660.886782264414;5780.306363236438;5819.883602328015;5924.496417804535;3230.3054843208824;3757.361248587692;3782.583915769208;3789.6020917803235;3810.4474851146983;4471.195184866755;5253.317068781034;5519.862003449946;5683.039351423752;5695.316562012585;5125.2568377585785;5264.552513036435;5290.206469029677;5419.376771671308;5477.260569823182;4945.4807245238735;5027.12025395547;5295.682259064469;5477.972610137083;5483.341998960151;4791.808327666854;4863.003084367767;5028.974855125701;5044.419891712353;5426.565874730091;5409.29679141709;5485.945140548543;5509.311336082989;5520.861978450571;5526.50261476469;4741.668083234141;4929.383827021622;4981.526754958273;5353.617640150096;5395.78416863321 11:29:24 INFO - PROCESS | 1659 | |5;yui.html;697.8534507753371;743.5538676793924;795.1055063054064;812.4921923797626;828.0601517277888;14787.038842475245;15102.471413500529;15345.616359590987;15350.616234594141;15378.846211537895;13991.370388332523;14005.649858753537;14057.437702491889;14081.577552673436;14087.366068526915;710.6446776611695;734.3280897978343;876.0319846569545;880.5817236812495;903.850864607339;890.9109089091104;902.1519771414881;905.3752910491772;905.5200743605899;906.8413027720155;958.2717134977397;958.2860768727273;960.3709570231509;960.8558716192557;961.8316794560959;747.0587060304001;870.6822009966317;949.2216382566298;950.5722424908749;954.6849539651898;705.7459314646723;707.4305184326615;707.5789905006513;707.9504434689574;708.6279703155833;14154;14220.07569507989;14232.92883535586;14239.501617443444;14253;13430.932845335808;13470.75336694032;13558.93220533891;13593.320333983262;13763.724725505434;11982.460789264416;12067.215630983957;12168.756624867541;12207.938960305142;12622.242665440102;4448.621867141264;4510.639148868116;4536.138133754576;4628.514006029386;4693.413323334583;77813.61093194498;83712.74430883542;84927.57536212342;85472.57263713704;86004;9891.357061791105;10445.529951152217;10778.838317425245;10794.082502987178;10871.239013269153;20456.386308410772;20649.17403303851;20717.58564828695;20746.962651867347;21084.683729744065;7759.573223472763;7827.686892524349;7864.056313242333;7958.164392738795;7966.561839098905;4125.381192821101;4263.168682107022;4350.543192964757;4407.074514351958;4432.977835110803;3307.7353811695007;3411.3177364527196;3415.197428604276;3418.709409700203;3418.760686751953;616.4975544930867;627.5732501898752;632.4434497642045;638.141699414287;641.425923798198;632.8576070384127;668.5420486966443;677.2245778583494;677.8136012596477;680.2415306932735;8538.146185381413;8844.778880528038;8871.600777965006;8874.86687699685;9165.450072995642;507.5077175140182;569.2770181869004;582.3012385137852;653.7147165169562;656.186328952096;3959.4852669152824;4198.244316023084;4212.515560710496;4292.935605965913;4319.265724826724;86491;88008.5599571998;89808;92127.61808572877;92175;78947.60526197332;79915;80023.7996430054;80251;81827.18172818315;74547;75121.62439187769;75522.62238688771;75537.6223118892;76794.2320576787;9422.440759951662;9442.763930901783;9446.480443575669;9465.47939863314;9565.139137477661;45009.549904500534;46314.76842615765;46875.765621171675;47573;47898;63272.05091923533;63351;63696;65627.67186164038;65856.67071664803;12309.815352769894;12402.937985310016;12410.937945310216;12527.749445010866;13051.673708157372;13859.514916977858;13899.235542045284;14539.854601454274;14622.853771462575;14693.779593306323;12984.163708165343;13004.609861704164;13160;13441.596752097452;13446.529371472343;13245;13255.00587455964;13280.867191327963;13543.458261669422;13632.93183534076;10447.58209671635;12705.36473176319;12820.551280705504;13235.933820330836;13250.403731832284;10637.361758294526;10752.731181720519;10870.347779133277;11252.943735281271;11333.773324533628;10892.564297428014;10932.712586048887;11035.062019728091;11196.328220306481;11224.887751122384 11:29:24 INFO - PROCESS | 1659 | __end_tp_report 11:29:24 INFO - PROCESS | 1659 | __start_cc_report 11:29:24 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2137 11:29:24 INFO - PROCESS | 1659 | __end_cc_report 11:29:24 INFO - PROCESS | 1659 | __startTimestamp1464373764299__endTimestamp 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | ------- Summary: start ------- 11:29:24 INFO - PROCESS | 1659 | Number of tests: 6 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5731.45 Median:5951.70 stddev:618.97 (10.4%) stddev-sans-first:594.12 11:29:24 INFO - PROCESS | 1659 | Values: 3436.7 3463.6 3467.0 3476.1 3537.1 5581.5 5599.6 5661.2 5705.3 5718.1 5054.5 5432.5 5713.4 5719.3 5735.0 5158.6 5214.8 5228.4 5235.4 5291.8 4398.5 5248.8 5280.0 5287.7 5292.1 5167.1 5178.3 5207.1 5215.6 5294.9 5273.9 5298.9 5299.2 5305.6 5349.8 5113.3 5132.0 5135.7 5244.3 5253.5 5533.8 5576.6 5580.4 5590.6 5614.9 6207.0 6256.7 6263.4 6270.0 6281.1 6134.6 6229.3 6258.6 6264.6 6295.7 5900.7 5908.5 5925.3 5926.6 5930.6 6269.3 6286.3 6293.2 6302.2 6305.0 5350.4 5847.8 6122.2 6138.3 6174.0 6186.8 6187.9 6192.0 6199.2 6207.8 6134.8 6162.7 6167.6 6172.3 6196.1 6108.7 6114.4 6122.1 6134.8 6156.7 4980.9 4984.4 4992.4 4998.4 5035.8 4409.0 4415.9 4418.7 4439.4 4444.2 5646.8 5694.4 5832.0 5843.2 5881.2 6106.9 6140.7 6148.7 6161.5 6166.4 5760.0 5782.5 5786.8 5809.9 5811.0 5364.4 5427.8 5431.6 5432.2 5443.3 6155.1 6249.8 6283.7 6298.4 6381.8 6155.7 6164.5 6172.9 6193.4 6194.1 6160.4 6307.8 6334.4 6375.3 6383.7 6104.3 6118.7 6118.8 6162.5 6180.8 6152.3 6181.4 6193.8 6194.8 6199.8 5989.6 6039.3 6100.3 6116.1 6117.3 6055.8 6089.5 6100.3 6112.1 6113.2 5852.2 5866.1 5951.7 5963.4 5964.2 6061.3 6099.1 6119.9 6124.1 6140.0 5798.1 5874.5 5947.1 5990.6 6005.8 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:70677.50 Median:38430.81 stddev:154866.37 (403.0%) stddev-sans-first:155211.19 11:29:24 INFO - PROCESS | 1659 | Values: 502.9 518.0 527.6 531.0 535.7 30130.7 42943.6 44122.8 44397.0 44457.0 33623.5 38283.9 43024.0 43449.3 43486.6 8493.0 9403.7 9613.0 9615.4 9626.4 8564.8 8620.4 8642.8 8644.6 11339.9 11309.2 11586.6 11621.4 11638.2 11648.1 11587.4 11706.0 11706.9 11707.0 11714.9 8473.1 8704.2 8707.0 8800.8 11454.1 39187.6 39944.0 40341.0 42296.6 42389.8 3468.3 3499.2 3508.5 3638.3 3861.5 31135.0 31734.0 31935.5 32165.5 32618.7 2789.9 3193.1 3273.6 3294.8 3298.3 505695.0 506982.0 507176.0 508446.0 508634.0 61360.7 61646.7 62143.7 62221.0 62226.7 63278.0 63434.4 63436.4 63518.7 63523.0 16068.9 16641.6 16647.8 16672.9 16703.0 12877.0 12905.9 12915.4 12936.7 12949.5 13704.9 14691.3 14971.5 16356.9 16669.9 6422.5 6503.5 6616.0 6663.7 6677.1 771.5 959.3 1051.9 1077.2 1169.5 30200.7 30572.2 30880.4 31063.8 31095.4 935.9 1102.7 1105.6 1107.9 1173.7 16286.6 16896.6 16904.5 16970.0 16979.4 666999.0 670457.0 671841.6 672667.0 675249.0 59267.7 60955.4 61083.0 61188.0 61194.0 1296.5 1343.8 1415.6 1429.0 1453.0 29781.7 30287.7 30432.0 31146.1 31614.9 47186.3 47219.5 47658.8 47754.3 47823.3 525151.5 549747.0 550737.0 552247.0 552508.0 24183.9 24362.9 24633.5 26463.2 26785.9 37055.3 39389.0 39592.4 39873.4 47132.8 35182.3 37950.6 39375.2 39486.0 40308.0 37872.0 38560.0 38773.4 39308.0 39503.2 38301.6 38777.2 38985.8 39071.8 39101.6 25065.4 33989.3 34575.5 34692.3 34709.7 25265.9 25430.6 25472.1 25693.4 26233.6 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:32767.51 Median:14632.22 stddev:91201.08 (623.3%) stddev-sans-first:91424.49 11:29:24 INFO - PROCESS | 1659 | Values: 378.5 380.3 386.2 396.7 405.5 19400.2 21289.9 21419.9 21561.0 21579.0 20228.3 20282.4 21174.7 21186.5 21218.2 5184.0 5855.0 6011.6 6027.1 6040.6 5866.3 5895.9 5958.9 6005.1 6013.7 4762.7 5879.3 5883.1 5916.1 5922.2 5758.7 5898.7 5913.5 5918.7 5923.3 4998.8 5121.1 5162.5 5170.3 5178.0 25439.0 25684.4 25867.1 26271.0 26454.2 2525.9 2638.2 2810.2 2843.2 2871.2 9550.7 10297.3 15230.4 15453.3 15559.2 1806.1 1815.0 1863.0 1893.4 1911.0 114122.0 114398.4 115098.0 115345.4 119762.0 11296.1 11434.4 11487.6 11524.2 11671.2 14428.4 14574.0 14600.6 14627.8 14628.3 8361.9 8476.9 8525.4 8612.0 8613.4 8301.2 8313.9 8338.0 8432.2 8433.7 3775.8 4395.4 4489.4 4495.9 4576.5 1664.3 1845.0 1891.2 1893.4 1926.9 715.9 957.9 1001.7 1064.1 1080.7 20004.9 20454.4 20602.6 20648.0 20695.9 873.7 898.5 1008.3 1023.1 1034.5 10419.2 10731.6 11043.6 11047.4 11057.0 539612.0 541391.0 548699.0 549016.0 554631.0 72686.5 109078.5 119372.4 124619.0 125989.0 935.1 953.3 970.9 971.2 974.0 15982.0 18330.0 18356.4 18451.3 18867.7 48477.0 48595.0 49628.0 50551.7 51040.5 66111.0 72124.2 72377.3 73345.6 73457.0 7825.0 7890.4 7993.6 8017.5 8069.9 11607.9 12151.7 12266.0 12453.1 12520.6 10651.0 10688.8 10767.8 10772.5 10871.8 9459.8 9512.5 9713.0 10330.0 10571.9 9205.3 10557.7 10709.6 11000.2 11133.9 7785.6 7923.5 7983.4 8010.8 8129.2 9037.4 10810.5 11009.2 11010.9 11056.6 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5622.85 Median:4971.37 stddev:3154.83 (63.5%) stddev-sans-first:3147.45 11:29:24 INFO - PROCESS | 1659 | Values: 1364.1 1509.5 1560.4 1753.8 1933.0 7680.5 8456.0 8703.3 8728.7 8820.7 8410.0 8695.5 8700.8 8701.1 8746.1 1900.5 2072.2 2153.9 2322.3 2359.6 2350.9 2384.9 2387.8 2388.0 2390.3 2104.5 2304.3 2322.9 2350.1 2352.6 2050.0 2263.9 2294.2 2335.6 2337.9 2166.6 2196.4 2342.5 2361.7 2367.2 7968.6 8663.9 8683.8 8689.9 8718.0 5160.7 5328.8 5333.5 5342.0 5342.8 3361.5 3447.1 3579.4 3598.5 3599.8 1081.7 1101.2 1106.0 1114.9 1120.7 11798.3 11802.2 11812.8 11821.8 11842.3 4631.6 4655.5 4760.8 4793.8 4809.8 9862.7 9868.5 9869.0 9873.4 9877.0 5311.7 5317.4 5318.6 5318.9 5321.0 4350.0 4497.5 4634.3 4841.0 4842.5 3868.7 3903.6 4006.6 4037.3 4043.8 1276.4 1319.1 1320.4 1328.4 1329.6 932.7 942.6 943.9 945.1 991.0 4942.8 5651.7 5689.9 5750.5 5849.5 928.1 963.6 978.4 984.8 985.1 3122.9 3920.0 3937.7 3991.6 3998.2 10033.4 10457.6 10558.4 10560.4 10570.0 10120.9 10514.6 10529.8 10533.1 10554.7 8074.4 8105.4 8220.5 8235.5 8251.2 5376.0 5774.2 5806.2 5825.7 5879.4 9746.6 9914.3 9923.5 9923.6 9938.4 9920.8 9962.4 9963.0 10190.0 10213.9 6457.6 6649.5 6655.7 6933.1 7087.0 7815.3 7920.3 7958.8 8213.4 8219.6 7213.9 7310.0 7332.9 7425.0 7508.6 7276.6 7285.6 7312.9 7320.6 7340.4 6758.5 6803.2 6874.8 6891.2 6899.2 4843.9 4884.3 4913.2 4931.5 4995.3 4878.6 4885.7 4913.0 4947.5 5094.9 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4362.40 Median:4804.38 stddev:1355.94 (28.2%) stddev-sans-first:1325.00 11:29:24 INFO - PROCESS | 1659 | Values: 299.1 304.4 313.5 317.2 318.2 4805.9 5084.2 5098.4 5236.2 5301.7 4241.7 5022.7 5201.2 5223.5 5241.6 3095.6 3368.9 3581.4 3996.1 4028.7 3468.8 3907.2 3931.4 4068.4 4072.4 3181.8 3870.8 3987.3 4039.8 4073.3 3984.8 4018.8 4035.4 4042.4 4057.3 3256.4 3275.9 3373.5 3477.1 3552.7 4286.8 4840.6 5045.6 5054.9 5107.7 3898.9 4049.3 4291.8 4355.7 4684.3 4741.1 5586.9 5595.1 5595.2 5612.8 3756.6 3918.5 3931.9 3933.4 3944.0 5007.3 5038.8 5599.5 5749.9 5789.3 3433.9 3970.6 3978.5 3985.7 3986.5 3430.4 4024.9 4044.3 4048.4 4068.1 3902.9 4088.9 4093.5 4218.1 4218.8 5295.3 5343.3 5569.2 5587.7 5595.3 1185.3 1337.7 1375.0 1376.3 1391.0 800.9 802.3 822.7 842.2 855.9 3737.6 3989.2 4230.6 4375.1 4389.8 4216.0 4522.6 4545.8 4802.9 4815.9 3857.0 4080.3 4082.9 4199.9 4414.7 2182.8 2556.6 2736.8 2980.2 2986.7 5234.6 5243.5 5850.6 5858.9 5994.5 4720.5 5109.9 5186.5 5601.0 5783.3 5826.9 5871.5 5884.0 5893.7 5933.7 5654.8 5666.5 5667.8 5670.2 5817.5 5135.8 5625.5 5628.4 5636.4 5652.3 5541.8 5660.9 5780.3 5819.9 5924.5 3230.3 3757.4 3782.6 3789.6 3810.4 4471.2 5253.3 5519.9 5683.0 5695.3 5125.3 5264.6 5290.2 5419.4 5477.3 4945.5 5027.1 5295.7 5478.0 5483.3 4791.8 4863.0 5029.0 5044.4 5426.6 5409.3 5485.9 5509.3 5520.9 5526.5 4741.7 4929.4 4981.5 5353.6 5395.8 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:18839.56 Median:25784.59 stddev:25708.18 (99.7%) stddev-sans-first:25744.21 11:29:24 INFO - PROCESS | 1659 | Values: 697.9 743.6 795.1 812.5 828.1 14787.0 15102.5 15345.6 15350.6 15378.8 13991.4 14005.6 14057.4 14081.6 14087.4 710.6 734.3 876.0 880.6 903.9 890.9 902.2 905.4 905.5 906.8 958.3 958.3 960.4 960.9 961.8 747.1 870.7 949.2 950.6 954.7 705.7 707.4 707.6 708.0 708.6 14154.0 14220.1 14232.9 14239.5 14253.0 13430.9 13470.8 13558.9 13593.3 13763.7 11982.5 12067.2 12168.8 12207.9 12622.2 4448.6 4510.6 4536.1 4628.5 4693.4 77813.6 83712.7 84927.6 85472.6 86004.0 9891.4 10445.5 10778.8 10794.1 10871.2 20456.4 20649.2 20717.6 20747.0 21084.7 7759.6 7827.7 7864.1 7958.2 7966.6 4125.4 4263.2 4350.5 4407.1 4433.0 3307.7 3411.3 3415.2 3418.7 3418.8 616.5 627.6 632.4 638.1 641.4 632.9 668.5 677.2 677.8 680.2 8538.1 8844.8 8871.6 8874.9 9165.5 507.5 569.3 582.3 653.7 656.2 3959.5 4198.2 4212.5 4292.9 4319.3 86491.0 88008.6 89808.0 92127.6 92175.0 78947.6 79915.0 80023.8 80251.0 81827.2 74547.0 75121.6 75522.6 75537.6 76794.2 9422.4 9442.8 9446.5 9465.5 9565.1 45009.5 46314.8 46875.8 47573.0 47898.0 63272.1 63351.0 63696.0 65627.7 65856.7 12309.8 12402.9 12410.9 12527.7 13051.7 13859.5 13899.2 14539.9 14622.9 14693.8 12984.2 13004.6 13160.0 13441.6 13446.5 13245.0 13255.0 13280.9 13543.5 13632.9 10447.6 12705.4 12820.6 13235.9 13250.4 10637.4 10752.7 10870.3 11252.9 11333.8 10892.6 10932.7 11035.1 11196.3 11224.9 11:29:24 INFO - PROCESS | 1659 | -------- Summary: end -------- 11:29:24 INFO - PROCESS | 1659 | 11:29:24 INFO - TEST-INFO | 1659: exit 0 11:29:24 INFO - TEST-OK | dromaeo_css | took 1116586ms 11:29:24 INFO - TEST-START | kraken 11:29:24 INFO - operating with platform_type : mac_ 11:29:24 INFO - Initialising browser for kraken test... 11:29:24 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/tmpa_Meyw/profile http://localhost:49270/getInfo.html) 11:29:27 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 11:29:27 INFO - PROCESS | 1669 | colorDepth:24 11:29:27 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 11:29:27 INFO - PROCESS | 1669 | __metrics 11:29:27 INFO - PROCESS | 1669 | 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" 11:29:27 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:29:27 INFO - TEST-INFO | 1669: exit 0 11:29:27 INFO - Browser initialized. 11:29:27 INFO - Running cycle 1/1 for kraken test... 11:29:27 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/T/tmpa_Meyw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:39 INFO - PROCESS | 1670 | RSS: Main: 241512448 11:29:39 INFO - PROCESS | 1670 | 11:29:42 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 11:29:43 INFO - PROCESS | 1670 | RSS: Main: 291373056 11:29:43 INFO - PROCESS | 1670 | 11:29:46 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 11:29:46 INFO - PROCESS | 1670 | RSS: Main: 300961792 11:29:46 INFO - PROCESS | 1670 | 11:29:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 11:29:51 INFO - PROCESS | 1670 | RSS: Main: 388771840 11:29:51 INFO - PROCESS | 1670 | 11:29:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 11:29:53 INFO - PROCESS | 1670 | RSS: Main: 287719424 11:29:53 INFO - PROCESS | 1670 | 11:29:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:29:56 INFO - PROCESS | 1670 | RSS: Main: 291737600 11:29:56 INFO - PROCESS | 1670 | 11:29:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 11:29:59 INFO - PROCESS | 1670 | RSS: Main: 328433664 11:29:59 INFO - PROCESS | 1670 | 11:30:02 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 11:30:03 INFO - PROCESS | 1670 | RSS: Main: 329703424 11:30:03 INFO - PROCESS | 1670 | 11:30:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 11:30:06 INFO - PROCESS | 1670 | RSS: Main: 329211904 11:30:06 INFO - PROCESS | 1670 | 11:30:08 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:30:08 INFO - PROCESS | 1670 | RSS: Main: 309452800 11:30:08 INFO - PROCESS | 1670 | 11:30:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:30:11 INFO - PROCESS | 1670 | RSS: Main: 316153856 11:30:11 INFO - PROCESS | 1670 | 11:30:13 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:30:14 INFO - PROCESS | 1670 | RSS: Main: 319123456 11:30:14 INFO - PROCESS | 1670 | 11:30:17 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:30:18 INFO - PROCESS | 1670 | RSS: Main: 324300800 11:30:18 INFO - PROCESS | 1670 | 11:30:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:30:21 INFO - PROCESS | 1670 | RSS: Main: 324976640 11:30:21 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 11:30:23 INFO - PROCESS | 1670 | RSS: Main: 367751168 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | __start_tp_report 11:30:23 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 11:30:23 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 11:30:23 INFO - PROCESS | 1670 | |i|pagename|runs| 11:30:23 INFO - PROCESS | 1670 | |0;ai-astar;111;106;113;113;115;148;169;98;154;167 11:30:23 INFO - PROCESS | 1670 | |1;audio-beat-detection;154;118;117;195;138;121;141;107;148;118 11:30:23 INFO - PROCESS | 1670 | |2;audio-dft;184;185;181;184;202;260;186;220;181;183 11:30:23 INFO - PROCESS | 1670 | |3;audio-fft;79;57;60;85;58;57;65;71;92;46 11:30:23 INFO - PROCESS | 1670 | |4;audio-oscillator;82;69;101;71;77;70;96;72;69;101 11:30:23 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;93;108;135;114;89;90;94;89;95;89 11:30:23 INFO - PROCESS | 1670 | |6;imaging-darkroom;95;94;95;94;93;94;94;99;94;94 11:30:23 INFO - PROCESS | 1670 | |7;imaging-desaturate;86;86;87;86;89;85;87;85;86;88 11:30:23 INFO - PROCESS | 1670 | |8;json-parse-financial;55;56;56;56;54;54;54;53;53;54 11:30:23 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;64;66;75;75;73;72;69;69;69;70 11:30:23 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;86;87;96;105;107;107;87;120;112 11:30:23 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;145;142;136;144;137;146;144;135;181;148 11:30:23 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;163;220;219;165;167;165;167;164;165;163 11:30:23 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;65;67;66;65;64;65;70;85;84;86 11:30:23 INFO - PROCESS | 1670 | __end_tp_report 11:30:23 INFO - PROCESS | 1670 | __start_cc_report 11:30:23 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2085 11:30:23 INFO - PROCESS | 1670 | __end_cc_report 11:30:23 INFO - PROCESS | 1670 | __startTimestamp1464373823761__endTimestamp 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | ------- Summary: start ------- 11:30:23 INFO - PROCESS | 1670 | Number of tests: 14 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:129.40 Median:151.00 stddev:26.98 (17.9%) stddev-sans-first:27.78 11:30:23 INFO - PROCESS | 1670 | Values: 111.0 106.0 113.0 113.0 115.0 148.0 169.0 98.0 154.0 167.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:139.50 stddev:25.93 (18.6%) stddev-sans-first:26.65 11:30:23 INFO - PROCESS | 1670 | Values: 154.0 118.0 117.0 195.0 138.0 121.0 141.0 107.0 148.0 118.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:196.60 Median:185.50 stddev:25.46 (13.7%) stddev-sans-first:26.59 11:30:23 INFO - PROCESS | 1670 | Values: 184.0 185.0 181.0 184.0 202.0 260.0 186.0 220.0 181.0 183.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:67.00 Median:68.00 stddev:14.47 (21.3%) stddev-sans-first:14.68 11:30:23 INFO - PROCESS | 1670 | Values: 79.0 57.0 60.0 85.0 58.0 57.0 65.0 71.0 92.0 46.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:71.50 stddev:13.46 (18.8%) stddev-sans-first:14.27 11:30:23 INFO - PROCESS | 1670 | Values: 82.0 69.0 101.0 71.0 77.0 70.0 96.0 72.0 69.0 101.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:99.60 Median:89.50 stddev:15.12 (16.9%) stddev-sans-first:15.84 11:30:23 INFO - PROCESS | 1670 | Values: 93.0 108.0 135.0 114.0 89.0 90.0 94.0 89.0 95.0 89.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 11:30:23 INFO - PROCESS | 1670 | Values: 95.0 94.0 95.0 94.0 93.0 94.0 94.0 99.0 94.0 94.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:86.50 stddev:1.27 (1.5%) stddev-sans-first:1.33 11:30:23 INFO - PROCESS | 1670 | Values: 86.0 86.0 87.0 86.0 89.0 85.0 87.0 85.0 86.0 88.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.18 (2.2%) stddev-sans-first:1.24 11:30:23 INFO - PROCESS | 1670 | Values: 55.0 56.0 56.0 56.0 54.0 54.0 54.0 53.0 53.0 54.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:3.61 (5.1%) stddev-sans-first:3.06 11:30:23 INFO - PROCESS | 1670 | Values: 64.0 66.0 75.0 75.0 73.0 72.0 69.0 69.0 69.0 70.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:99.30 Median:86.00 stddev:12.51 (14.5%) stddev-sans-first:12.31 11:30:23 INFO - PROCESS | 1670 | Values: 86.0 86.0 87.0 96.0 105.0 107.0 107.0 87.0 120.0 112.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:144.50 stddev:13.15 (9.1%) stddev-sans-first:13.94 11:30:23 INFO - PROCESS | 1670 | Values: 145.0 142.0 136.0 144.0 137.0 146.0 144.0 135.0 181.0 148.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:166.00 stddev:23.07 (13.9%) stddev-sans-first:24.00 11:30:23 INFO - PROCESS | 1670 | Values: 163.0 220.0 219.0 165.0 167.0 165.0 167.0 164.0 165.0 163.0 11:30:23 INFO - PROCESS | 1670 | 11:30:23 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.70 Median:68.50 stddev:9.33 (13.6%) stddev-sans-first:9.58 11:30:23 INFO - PROCESS | 1670 | Values: 65.0 67.0 66.0 65.0 64.0 65.0 70.0 85.0 84.0 86.0 11:30:23 INFO - PROCESS | 1670 | -------- Summary: end -------- 11:30:23 INFO - PROCESS | 1670 | 11:30:24 INFO - TEST-INFO | 1670: exit 0 11:30:24 INFO - TEST-OK | kraken | took 59693ms 11:30:24 INFO - SUITE-END | took 1176s 11:30:24 INFO - Completed test suite (00:19:36) 11:30:24 INFO - javascript benchmark 11:30:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3436.7422443316755, 3463.6016858061316, 3467.0465621953967, 3476.1309672581856, 3537.1334023164313, 5581.5255703265275, 5599.608027438081, 5661.23573317602, 5705.315362156537, 5718.142278658198, 5054.519820617035, 5432.456754324566, 5713.428657134285, 5719.256496655429, 5735.0250457422235, 5158.561522270602, 5214.843554693346, 5228.372595288571, 5235.371755389339, 5291.841244762663, 4398.472183338004, 5248.816291429782, 5280.049591073605, 5287.735613219343, 5292.073887069767, 5167.069927413064, 5178.275041494194, 5207.062728708831, 5215.6349055566125, 5294.92057619136, 5273.945210957804, 5298.92051619226, 5299.231611416361, 5305.628605997582, 5349.812756553541, 5113.309703190096, 5131.974340128276, 5135.666181698178, 5244.292020577212, 5253.50091741284, 5533.778648854041, 5576.553875689935, 5580.441955804427, 5590.636608620467, 5614.9157762633595, 6207.0379091240475, 6256.71844766982, 6263.404976527223, 6270.0, 6281.089242059876, 6134.60125091872, 6229.25248970122, 6258.624482531017, 6264.561480696353, 6295.653739044351, 5900.734466948998, 5908.468237858613, 5925.259342582177, 5926.555508336892, 5930.55520835939, 6269.279032911274, 6286.308506064329, 6293.1504246927, 6302.212223472066, 6304.968475157595, 5350.384705758887, 5847.824565262964, 6122.173506576555, 6138.296437970319, 6174.0, 6186.845328866814, 6187.876242475215, 6192.040233763774, 6199.225096862892, 6207.751689932443, 6134.8159555213415, 6162.691865406801, 6167.599106058092, 6172.321044685081, 6196.10156527301, 6108.736706092798, 6114.449699527064, 6122.12371750169, 6134.834601026089, 6156.6921653918025, 4980.9252861207115, 4984.352034235526, 4992.375953005874, 4998.375203099613, 5035.823746168867, 4409.0, 4415.889602759957, 4418.7127836690515, 4439.445069366329, 4444.2222611042935, 5646.774129034875, 5694.402087780723, 5832.008558545058, 5843.152742852262, 5881.235439392937, 6106.885399019288, 6140.662263575547, 6148.739508400767, 6161.537884658668, 6166.445019948226, 5760.0, 5782.508486778587, 5786.768529258867, 5809.854753631109, 5811.041178205549, 5364.436734142937, 5427.83716488506, 5431.646942948696, 5432.2123292123215, 5443.319585051869, 6155.10750941111, 6249.750009999549, 6283.654399008098, 6298.401651843067, 6381.776637817654, 6155.692215389123, 6164.537659675452, 6172.9074063889975, 6193.38066193377, 6194.101855230876, 6160.44555989963, 6307.842303942254, 6334.398232167937, 6375.266844312963, 6383.744650213939, 6104.32852386246, 6118.710550942845, 6118.832384214075, 6162.476189523762, 6180.783672571439, 6152.323244443018, 6181.443670069715, 6193.783217587363, 6194.845128871814, 6199.752009919553, 5989.64062156272, 6039.275286965417, 6100.298465676504, 6116.113163591165, 6117.296510901303, 6055.7577696893395, 6089.48239399656, 6100.267967843887, 6112.083187521907, 6113.23584551931, 5852.209951656642, 5866.120081987565, 5951.702414879325, 5963.373845746134, 5964.194833697396, 6061.272647282354, 6099.146119543356, 6119.908201377072, 6124.081387791867, 6140.0134868502855, 5798.072308430631, 5874.500667443315, 5947.137665038632, 5990.580659353804, 6005.789797357072], "value": 5693.421926847797, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.8835984115263, 517.9766910489028, 527.6069328350378, 530.9681419114855, 535.7133933345658, 30130.698693013062, 42943.57056429435, 44122.779386103066, 44397.0, 44457.0, 33623.49564756524, 38283.85148445551, 43024.0, 43449.34825977613, 43486.565134348646, 8493.0, 9403.670871519498, 9613.0, 9615.423074615546, 9626.422414655108, 8564.828703425928, 8620.353473489513, 8642.783930401727, 8644.567771611426, 11339.886601133965, 11309.151813613971, 11586.59446919358, 11621.418929053514, 11638.243514171552, 11648.126390520745, 11587.42062896856, 11706.0, 11706.941465292704, 11707.0, 11714.941425292904, 8473.067962524117, 8704.173103555153, 8706.956465217634, 8800.823983520293, 11454.083673306115, 39187.608123918966, 39944.0, 40340.991475213355, 42296.61627069827, 42389.78805105955, 3468.3410152071024, 3499.230169362736, 3508.475166543108, 3638.2541578976247, 3861.498005259298, 31135.0, 31734.0, 31935.520967185512, 32165.517517237262, 32618.673813262038, 2789.8840463814436, 3193.050711695075, 3273.6071671399345, 3294.7858389204625, 3298.307355455357, 505695.0, 506982.0, 507176.0, 508446.0, 508634.0, 61360.69319653462, 61646.691766540884, 62143.68928155421, 62221.0, 62226.68886655538, 63278.0, 63434.365656342845, 63436.365636343966, 63518.72962540816, 63523.0, 16068.919655401649, 16641.583960401087, 16647.83352166487, 16672.916635416746, 16703.0, 12877.0, 12905.935470322589, 12915.354232288535, 12936.741265174644, 12949.482020719253, 13704.862951370358, 14691.265436728121, 14971.475998340005, 16356.918215408847, 16669.91665041667, 6422.472157129213, 6503.544751867324, 6615.966920165369, 6663.666816659148, 6677.131972843499, 771.4908160613968, 959.2709540749081, 1051.8790339111101, 1077.1705786544317, 1169.502961241469, 30200.69799301979, 30572.23569410738, 30880.38239235203, 31063.844680776452, 31095.378092438026, 935.8582529314226, 1102.652664410708, 1105.5909313554037, 1107.8781334053247, 1173.6889724223088, 16286.592835178979, 16896.57758556046, 16904.492865214062, 16970.0, 16979.405720799714, 666999.0, 670457.0, 671841.6407917929, 672667.0, 675249.0, 59267.70366148141, 60955.39044609497, 61083.0, 61188.0, 61194.0, 1296.5267677297907, 1343.8454577723687, 1415.5611760354323, 1428.9642758930902, 1453.0337325678518, 29781.702182977893, 30287.68011168153, 30432.0, 31146.065618031495, 31614.893478728132, 47186.29220561763, 47219.52780472151, 47658.761706191246, 47754.28368574405, 47823.28265075957, 525151.4969700661, 549747.0, 550737.0, 552247.0, 552508.0, 24183.879080604485, 24362.87818560896, 24633.507329852942, 26463.190336531137, 26785.866070669523, 37055.25889482141, 39389.0, 39592.40611390774, 39873.40189897096, 47132.7643361781, 35182.29635407329, 37950.62049379581, 39375.21249574935, 39486.0, 40308.0, 37872.0, 38560.0, 38773.41839872461, 39308.0, 39503.20993580055, 38301.61698382981, 38777.2244555113, 38985.8050709756, 39071.80464097661, 39101.60898391093, 25065.373365666004, 33989.32021359509, 34575.481367779, 34692.30615387628, 34709.65290347064, 25265.87367063153, 25430.618540721534, 25472.108476203244, 25693.3576660585, 26233.606495902193], "value": 20048.617931064695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.4871499118695, 380.34390676083757, 386.22561763663833, 396.6885994494323, 405.50933370621544, 19400.22399104036, 21289.935503224817, 21419.892900535513, 21561.029753661085, 21579.0, 20228.29200977966, 20282.391528254175, 21174.682379764316, 21186.47033824155, 21218.151273949024, 5183.974080129613, 5855.0, 6011.639301641893, 6027.126066720323, 6040.607360521574, 5866.266716660418, 5895.911561326584, 5958.940410595882, 6005.069214271794, 6013.6993150342305, 4762.666613337068, 5879.265091863517, 5883.058710606304, 5916.053431450969, 5922.2005029321135, 5758.74085666146, 5898.705064746767, 5913.526917846562, 5918.733656985403, 5923.259592550931, 4998.750312421895, 5121.129408000649, 5162.5095615916425, 5170.276161337416, 5178.0, 25439.0, 25684.357891052874, 25867.09465168729, 26271.0, 26454.206373808818, 2525.9242222733346, 2638.20853743878, 2810.2412348665744, 2843.1612674260937, 2871.1530098620956, 9550.718293604965, 10297.279190456671, 15230.390784368612, 15453.304601292968, 15559.22203889801, 1806.0879255975779, 1814.9818501814946, 1862.975363550042, 1893.3657224829699, 1910.9713354299697, 114122.0, 114398.42800786026, 115098.0, 115345.4232728831, 119762.0, 11296.096312294996, 11434.371109588887, 11487.597934072268, 11524.193306468467, 11671.241369311138, 14428.422863085569, 14574.0, 14600.634984125269, 14627.78058329126, 14628.341724622418, 8361.916380836114, 8476.915230847613, 8525.44584601999, 8612.009618893782, 8613.353998450142, 8301.211384918422, 8313.875291870627, 8338.04112527055, 8432.198941100585, 8433.704820331259, 3775.8300876460626, 4395.428594282723, 4489.416375871181, 4495.910081798411, 4576.4508259009135, 1664.334266293468, 1844.981550184481, 1891.2056936086876, 1893.4414347767442, 1926.9325573604856, 715.8601243074773, 957.8563215517728, 1001.7245257554085, 1064.1167830700654, 1080.7298175456135, 20004.890439191346, 20454.38636840897, 20602.58794824125, 20648.0, 20695.8965205173, 873.6723728601775, 898.5462341517492, 1008.324422636835, 1023.0945613132432, 1034.534459493231, 10419.166466682647, 10731.624393146358, 11043.613473528389, 11047.392393418439, 11057.009738247032, 539612.0, 541391.0, 548699.0, 549016.0, 554631.0, 72686.54626907432, 109078.45460772804, 119372.40313798375, 124619.0, 125989.0, 935.1256575102301, 953.3136141977765, 970.9126178643955, 971.2327261463365, 974.045435473226, 15982.0, 18330.0, 18356.449306520826, 18451.261949521868, 18867.679262451496, 48477.0, 48595.0, 49628.007439851724, 50551.74724126503, 51040.48959510357, 66111.0, 72124.19689507804, 72377.34831729828, 73345.6332718333, 73457.0, 7824.9608751955875, 7890.447668663139, 7993.560354180576, 8017.478863873829, 8069.919300807152, 11607.941960290145, 12151.696207594881, 12266.0, 12453.06602004835, 12520.624381268577, 10651.0, 10688.786224275627, 10767.838482422927, 10772.461376930965, 10871.782564348827, 9459.763505912408, 9512.476813775309, 9712.951435242778, 10329.96700329961, 10571.894281057092, 9205.263578913804, 10557.683269501927, 10709.591656213563, 11000.174986876176, 11133.886611339125, 7785.571793551182, 7923.524588524707, 7983.441159119038, 8010.759677209693, 8129.227723366271, 9037.412568183046, 10810.51352689115, 11009.174311926798, 11010.889891100986, 11056.613018544635], "value": 9465.655042180939, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1364.0860623382332, 1509.4867744966712, 1560.3914473355392, 1753.7720096387468, 1933.0, 7680.500767450112, 8456.0, 8703.303735701145, 8728.650853965833, 8820.735377938656, 8409.957950210255, 8695.478271303726, 8700.82538857254, 8701.129887011295, 8746.081661425555, 1900.4678689966763, 2072.2229164063474, 2153.9461513462134, 2322.3033090072995, 2359.6224604063354, 2350.9412264693347, 2384.9284521464383, 2387.8447900886385, 2387.95224095518, 2390.3426557696685, 2104.515961328895, 2304.274153641606, 2322.941926451835, 2350.0717216699395, 2352.5647755165264, 2049.9692504612444, 2263.864168149916, 2294.1855641247366, 2335.591271527493, 2337.8597284163006, 2166.599179151862, 2196.417949243452, 2342.4612339161918, 2361.6575596538405, 2367.242482405631, 7968.561729104897, 8663.913360866436, 8683.782905427352, 8689.869651955225, 8717.956410217908, 5160.741962901878, 5328.8401347959625, 5333.546648534879, 5341.973290133564, 5342.759575819097, 3361.4957756336503, 3447.052060683307, 3579.4451859961746, 3598.4962105305017, 3599.766015209003, 1081.7187531241855, 1101.1576144249655, 1105.9944700276528, 1114.8662160540787, 1120.6806060272866, 11798.292102473879, 11802.173847830656, 11812.822807657978, 11821.763564728742, 11842.28946263227, 4631.606313463326, 4655.464621568495, 4760.761961901961, 4793.784279707352, 4809.759512024385, 9862.654807081717, 9868.457234852152, 9869.0, 9873.40759554429, 9877.0, 5311.734413279398, 5317.388500322435, 5318.627696061239, 5318.9202161967605, 5320.973395133077, 4350.0, 4497.460304763449, 4634.281686338623, 4841.0, 4842.491538388418, 3868.7485313454536, 3903.6291552302487, 4006.639402453793, 4037.313656678372, 4043.7775922324554, 1276.4192292506887, 1319.0502837956656, 1320.4124164746788, 1328.4096012775128, 1329.6277042428135, 932.69687351611, 942.6323733743845, 943.9433633981984, 945.0643862576001, 991.0386924683046, 4942.777575009133, 5651.689157096317, 5689.886202276014, 5750.5112065474905, 5849.502792262705, 928.0811996123789, 963.629002833914, 978.4422878958925, 984.7538115471132, 985.0543478260806, 3122.8906988255303, 3920.0, 3937.665298449664, 3991.5808840071954, 3998.200359928026, 10033.44816035125, 10457.581696732046, 10558.419286939292, 10560.419176945188, 10570.048695617432, 10120.949395252977, 10514.63198788054, 10529.789404211873, 10533.104686101615, 10554.683359499228, 8074.394420418492, 8105.392095592854, 8220.547869867214, 8235.464694794819, 8251.216134467217, 5376.0, 5774.249347584955, 5806.2451881255165, 5825.650460972355, 5879.441453061952, 9746.610135594781, 9914.30599858003, 9923.503824808875, 9923.553440095357, 9938.403695778277, 9920.759905011873, 9962.402255864672, 9963.0, 10190.0, 10213.897861021593, 6457.61254324742, 6649.46804255668, 6655.73377064912, 6933.064036355027, 7086.964565177141, 7815.335696465864, 7920.287174154354, 7958.761237162895, 8213.425060245725, 8219.630116644885, 7213.855722885408, 7309.963450182928, 7332.890006649798, 7425.0, 7508.587027713528, 7276.563406195645, 7285.562866228043, 7312.853742925218, 7320.560766354036, 7340.376068034277, 6758.493113016445, 6803.183617965876, 6874.793756187322, 6891.173059232924, 6899.206591242071, 4843.878903027453, 4884.340614017062, 4913.164761990542, 4931.5315045070665, 4995.275685025724, 4878.609711223165, 4885.731284779229, 4913.0, 4947.45577986414, 5094.9490505095955], "value": 4535.447692336408, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.07585560617684, 304.4337532190126, 313.54379378005007, 317.21172885379826, 318.1727508477956, 4805.870620404202, 5084.211947148188, 5098.362704661918, 5236.240745091959, 5301.674581354661, 4241.660667146621, 5022.69409953412, 5201.193814958678, 5223.529882310591, 5241.554467870235, 3095.5975723155957, 3368.949465758016, 3581.426971684531, 3996.100877302613, 4028.738132021424, 3468.75124955016, 3907.2380885727293, 3931.3513270310364, 4068.3726509396183, 4072.3891416287497, 3181.8249996250197, 3870.774304774766, 3987.342088555385, 4039.757614543122, 4073.287174744423, 3984.8007599619905, 4018.7789671568053, 4035.3946907963746, 4042.3936409538655, 4057.2696914555368, 3256.4301247281633, 3275.9353210206777, 3373.4602463605706, 3477.0959550516795, 3552.6980206682465, 4286.76422796746, 4840.588549973257, 5045.6215783816, 5054.873628159326, 5107.744612769384, 3898.863539776094, 4049.339770694031, 4291.763952982585, 4355.695101342907, 4684.344191813149, 4741.051789642051, 5586.944130558723, 5595.062592139119, 5595.21666966625, 5612.7754889804355, 3756.572502449052, 3918.4710064141545, 3931.941020884689, 3933.449317095609, 3943.98028009861, 5007.298978143026, 5038.823641172578, 5599.488138202662, 5749.88500229993, 5789.2763404574425, 3433.8626454942023, 3970.622790834866, 3978.5026871641044, 3985.70107241958, 3986.4618276532296, 3430.3996800559803, 4024.852916918664, 4044.2922488564973, 4048.352263637804, 4068.145689405199, 3902.926695158854, 4088.9386659200136, 4093.5087789465456, 4218.135282267128, 4218.7679677618025, 5295.285136506523, 5343.305370301836, 5569.164625306236, 5587.720613969285, 5595.328560572676, 1185.2829038431796, 1337.7057047449546, 1374.9862501374857, 1376.3256004557695, 1390.9721805563831, 800.9479383840031, 802.2939812964554, 822.7042408207097, 842.2377748137945, 855.8758979947997, 3737.6262373762406, 3989.2247949662124, 4230.576942305806, 4375.134287041992, 4389.824407023683, 4215.978920105441, 4522.5929666330185, 4545.840810593278, 4802.879928001758, 4815.881012354553, 3857.0, 4080.3471444568727, 4082.9387559186634, 4199.895002624898, 4414.690971632019, 2182.8144607708205, 2556.642070110186, 2736.808423410382, 2980.240038790138, 2986.731194192533, 5234.633575649744, 5243.501867322599, 5850.5904586678525, 5858.853528661817, 5994.490468310242, 4720.511421220846, 5109.948900511022, 5186.5332120108615, 5601.0, 5783.339663754636, 5826.912596310974, 5871.5027667944405, 5884.0, 5893.705314734246, 5933.732982015819, 5654.7842213924205, 5666.490015898589, 5667.829965101054, 5670.234518339971, 5817.534597232296, 5135.794568217229, 5625.46558076982, 5628.439060227504, 5636.436356364331, 5652.349979752381, 5541.783286685197, 5660.886782264414, 5780.306363236438, 5819.883602328015, 5924.496417804535, 3230.3054843208824, 3757.361248587692, 3782.583915769208, 3789.6020917803235, 3810.4474851146983, 4471.195184866755, 5253.317068781034, 5519.862003449946, 5683.039351423752, 5695.316562012585, 5125.2568377585785, 5264.552513036435, 5290.206469029677, 5419.376771671308, 5477.260569823182, 4945.4807245238735, 5027.12025395547, 5295.682259064469, 5477.972610137083, 5483.341998960151, 4791.808327666854, 4863.003084367767, 5028.974855125701, 5044.419891712353, 5426.565874730091, 5409.29679141709, 5485.945140548543, 5509.311336082989, 5520.861978450571, 5526.50261476469, 4741.668083234141, 4929.383827021622, 4981.526754958273, 5353.617640150096, 5395.78416863321], "value": 3936.8713320827565, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.8534507753371, 743.5538676793924, 795.1055063054064, 812.4921923797626, 828.0601517277888, 14787.038842475245, 15102.471413500529, 15345.616359590987, 15350.616234594141, 15378.846211537895, 13991.370388332523, 14005.649858753537, 14057.437702491889, 14081.577552673436, 14087.366068526915, 710.6446776611695, 734.3280897978343, 876.0319846569545, 880.5817236812495, 903.850864607339, 890.9109089091104, 902.1519771414881, 905.3752910491772, 905.5200743605899, 906.8413027720155, 958.2717134977397, 958.2860768727273, 960.3709570231509, 960.8558716192557, 961.8316794560959, 747.0587060304001, 870.6822009966317, 949.2216382566298, 950.5722424908749, 954.6849539651898, 705.7459314646723, 707.4305184326615, 707.5789905006513, 707.9504434689574, 708.6279703155833, 14154.0, 14220.07569507989, 14232.92883535586, 14239.501617443444, 14253.0, 13430.932845335808, 13470.75336694032, 13558.93220533891, 13593.320333983262, 13763.724725505434, 11982.460789264416, 12067.215630983957, 12168.756624867541, 12207.938960305142, 12622.242665440102, 4448.621867141264, 4510.639148868116, 4536.138133754576, 4628.514006029386, 4693.413323334583, 77813.61093194498, 83712.74430883542, 84927.57536212342, 85472.57263713704, 86004.0, 9891.357061791105, 10445.529951152217, 10778.838317425245, 10794.082502987178, 10871.239013269153, 20456.386308410772, 20649.17403303851, 20717.58564828695, 20746.962651867347, 21084.683729744065, 7759.573223472763, 7827.686892524349, 7864.056313242333, 7958.164392738795, 7966.561839098905, 4125.381192821101, 4263.168682107022, 4350.543192964757, 4407.074514351958, 4432.977835110803, 3307.7353811695007, 3411.3177364527196, 3415.197428604276, 3418.709409700203, 3418.760686751953, 616.4975544930867, 627.5732501898752, 632.4434497642045, 638.141699414287, 641.425923798198, 632.8576070384127, 668.5420486966443, 677.2245778583494, 677.8136012596477, 680.2415306932735, 8538.146185381413, 8844.778880528038, 8871.600777965006, 8874.86687699685, 9165.450072995642, 507.5077175140182, 569.2770181869004, 582.3012385137852, 653.7147165169562, 656.186328952096, 3959.4852669152824, 4198.244316023084, 4212.515560710496, 4292.935605965913, 4319.265724826724, 86491.0, 88008.5599571998, 89808.0, 92127.61808572877, 92175.0, 78947.60526197332, 79915.0, 80023.7996430054, 80251.0, 81827.18172818315, 74547.0, 75121.62439187769, 75522.62238688771, 75537.6223118892, 76794.2320576787, 9422.440759951662, 9442.763930901783, 9446.480443575669, 9465.47939863314, 9565.139137477661, 45009.549904500534, 46314.76842615765, 46875.765621171675, 47573.0, 47898.0, 63272.05091923533, 63351.0, 63696.0, 65627.67186164038, 65856.67071664803, 12309.815352769894, 12402.937985310016, 12410.937945310216, 12527.749445010866, 13051.673708157372, 13859.514916977858, 13899.235542045284, 14539.854601454274, 14622.853771462575, 14693.779593306323, 12984.163708165343, 13004.609861704164, 13160.0, 13441.596752097452, 13446.529371472343, 13245.0, 13255.00587455964, 13280.867191327963, 13543.458261669422, 13632.93183534076, 10447.58209671635, 12705.36473176319, 12820.551280705504, 13235.933820330836, 13250.403731832284, 10637.361758294526, 10752.731181720519, 10870.347779133277, 11252.943735281271, 11333.773324533628, 10892.564297428014, 10932.712586048887, 11035.062019728091, 11196.328220306481, 11224.887751122384], "value": 7296.437286188871, "unit": "score"}], "name": "dromaeo_css", "value": 7212.566106650632, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 113.0, 113.0, 115.0, 148.0, 169.0, 98.0, 154.0, 167.0], "value": 129.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 118.0, 117.0, 195.0, 138.0, 121.0, 141.0, 107.0, 148.0, 118.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 181.0, 184.0, 202.0, 260.0, 186.0, 220.0, 181.0, 183.0], "value": 196.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 60.0, 85.0, 58.0, 57.0, 65.0, 71.0, 92.0, 46.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 101.0, 71.0, 77.0, 70.0, 96.0, 72.0, 69.0, 101.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 108.0, 135.0, 114.0, 89.0, 90.0, 94.0, 89.0, 95.0, 89.0], "value": 99.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 94.0, 93.0, 94.0, 94.0, 99.0, 94.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 89.0, 85.0, 87.0, 85.0, 86.0, 88.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 56.0, 54.0, 54.0, 54.0, 53.0, 53.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 75.0, 75.0, 73.0, 72.0, 69.0, 69.0, 69.0, 70.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 96.0, 105.0, 107.0, 107.0, 87.0, 120.0, 112.0], "value": 99.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 142.0, 136.0, 144.0, 137.0, 146.0, 144.0, 135.0, 181.0, 148.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 220.0, 219.0, 165.0, 167.0, 165.0, 167.0, 164.0, 165.0, 163.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 65.0, 64.0, 65.0, 70.0, 85.0, 84.0, 86.0], "value": 71.7, "unit": "score"}], "name": "kraken", "value": 1507.5, "alertThreshold": 2.0}]} 11:30:24 INFO - Return code: 0 11:30:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:30:25 ERROR - # TBPL SUCCESS # 11:30:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:30:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:30:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:30:25 INFO - 2016-05-27 11:30:25.032 screenresolution[1675:9665] starting screenresolution argv=screenresolution get 11:30:25 INFO - 2016-05-27 11:30:25.034 screenresolution[1675:9665] Display 0: 1600x1200x32@60 11:30:25 INFO - 2016-05-27 11:30:25.040 screenresolution[1676:9668] starting screenresolution argv=screenresolution list 11:30:25 INFO - Available Modes on Display 0 11:30:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:30:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:30:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:30:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:30:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:30:25 INFO - Intel Iris: 11:30:25 INFO - Chipset Model: Intel Iris 11:30:25 INFO - Type: GPU 11:30:25 INFO - Bus: Built-In 11:30:25 INFO - VRAM (Dynamic, Max): 1536 MB 11:30:25 INFO - Vendor: Intel (0x8086) 11:30:25 INFO - Device ID: 0x0a2e 11:30:25 INFO - Revision ID: 0x0009 11:30:25 INFO - Displays: 11:30:25 INFO - Display: 11:30:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:30:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:30:25 INFO - Display Serial Number: 1600x1200 60 11:30:25 INFO - Main Display: Yes 11:30:25 INFO - Mirror: Off 11:30:25 INFO - Online: Yes 11:30:25 INFO - Rotation: Supported 11:30:25 INFO - Return code: 0 11:30:25 INFO - Running post-action listener: _resource_record_post_action 11:30:25 INFO - ##### 11:30:25 INFO - ##### Finished run-tests step (success) 11:30:25 INFO - ##### 11:30:25 INFO - Running post-run listener: _resource_record_post_run 11:30:25 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36914688; Write bytes: 696346624; Read time: 237; Write time: 1930 11:30:25 INFO - TinderboxPrint: CPU usage
53.6% 11:30:25 INFO - TinderboxPrint: I/O read bytes / time
36,914,688 / 237 11:30:25 INFO - TinderboxPrint: I/O write bytes / time
696,346,624 / 1,930 11:30:25 INFO - TinderboxPrint: CPU idle
2,249.3 (46.4%) 11:30:25 INFO - TinderboxPrint: CPU system
964.2 (19.9%) 11:30:25 INFO - TinderboxPrint: CPU user
1,636.1 (33.7%) 11:30:25 INFO - TinderboxPrint: Swap in / out
434,868,224 / 0 11:30:25 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 398658048; Write bytes: 397678592; Read time: 29742; Write time: 829 11:30:25 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35132928; Write bytes: 294899712; Read time: 222; Write time: 1086 11:30:25 INFO - Running post-run listener: _upload_blobber_files 11:30:25 INFO - Blob upload gear active. 11:30:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:30:25 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 11:30:25 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'] 11:30:25 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 11:30:26 INFO - (blobuploader) - INFO - Open directory for files ... 11:30:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:30:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:30:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:27 INFO - (blobuploader) - INFO - Done attempting. 11:30:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:30:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:30:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:28 INFO - (blobuploader) - INFO - Done attempting. 11:30:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:30:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:30:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:30 INFO - (blobuploader) - INFO - Done attempting. 11:30:30 INFO - (blobuploader) - INFO - Iteration through files over. 11:30:30 INFO - Return code: 0 11:30:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:30:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:30:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9302ed53d3f6819f1804b1e92aa511626d3c2ee0094f7d57aa085fe9b23275c3952d4e40c5e5caf5f99b83d9ccccef7c75ecabab156ffa7cb6208c3143b46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8274b41ae0510b5afbaaaf2b1c5635de111db7abba4f2022977eaf5e1322e5a74401e974dc63c5c1bf9d684588071230dc10c13c9429d1f3df58b5572b1df4d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058f31de06f3b9ecb9ae77deba510b957b6feec87ae8c221438be7a3af5521156e174bfec327cd9dad26063e8babc9caf92e02e170324469a96805bef1cbb7ce"} 11:30:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:30:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:30:30 INFO - Contents: 11:30:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a9302ed53d3f6819f1804b1e92aa511626d3c2ee0094f7d57aa085fe9b23275c3952d4e40c5e5caf5f99b83d9ccccef7c75ecabab156ffa7cb6208c3143b46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8274b41ae0510b5afbaaaf2b1c5635de111db7abba4f2022977eaf5e1322e5a74401e974dc63c5c1bf9d684588071230dc10c13c9429d1f3df58b5572b1df4d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058f31de06f3b9ecb9ae77deba510b957b6feec87ae8c221438be7a3af5521156e174bfec327cd9dad26063e8babc9caf92e02e170324469a96805bef1cbb7ce"} 11:30:30 INFO - Running post-run listener: copy_logs_to_upload_dir 11:30:30 INFO - Copying logs to upload dir... 11:30:30 INFO - mkdir: /builds/slave/test/build/upload/logs 11:30:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.851879 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-27 11:30:30.845124) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 11:30:30.848463) ========= 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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners TMPDIR=/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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/a9302ed53d3f6819f1804b1e92aa511626d3c2ee0094f7d57aa085fe9b23275c3952d4e40c5e5caf5f99b83d9ccccef7c75ecabab156ffa7cb6208c3143b46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8274b41ae0510b5afbaaaf2b1c5635de111db7abba4f2022977eaf5e1322e5a74401e974dc63c5c1bf9d684588071230dc10c13c9429d1f3df58b5572b1df4d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058f31de06f3b9ecb9ae77deba510b957b6feec87ae8c221438be7a3af5521156e174bfec327cd9dad26063e8babc9caf92e02e170324469a96805bef1cbb7ce"} build_url:https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011095 build_url: 'https://queue.taskcluster.net/v1/task/VAblgGN6RKWdxpVP1mog-w/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/a9302ed53d3f6819f1804b1e92aa511626d3c2ee0094f7d57aa085fe9b23275c3952d4e40c5e5caf5f99b83d9ccccef7c75ecabab156ffa7cb6208c3143b46a0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8274b41ae0510b5afbaaaf2b1c5635de111db7abba4f2022977eaf5e1322e5a74401e974dc63c5c1bf9d684588071230dc10c13c9429d1f3df58b5572b1df4d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/058f31de06f3b9ecb9ae77deba510b957b6feec87ae8c221438be7a3af5521156e174bfec327cd9dad26063e8babc9caf92e02e170324469a96805bef1cbb7ce"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 11:30:30.879567) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:30:30.879948) ========= 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.BjiH1MopGh/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.kJsLMQ9ZJr/Listeners TMPDIR=/var/folders/mb/mpkj7hkd5b55mcsqlhlvs3x000000w/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.005127 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 11:30:30.929960) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 11:30:30.930303) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 11:30:30.930641) ========= ========= Total master_lag: 0.21 =========