builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0314 starttime: 1468253592.86 results: success (0) buildid: 20160711072628 builduid: 7c42e211bde54966b919f26307bdbb1a revision: 42dcbbe5f18d3f50ec1af613f12bc9cd11dfaf0b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:12.861940) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:12.862394) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:12.862678) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:12.887152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:12.887538) ========= 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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-07-11 09:13:12-- 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% 168M=0s 2016-07-11 09:13:13 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121234 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:13.033498) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:13.034046) ========= 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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.025416 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:13.084141) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 09:13:13.084515) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 42dcbbe5f18d3f50ec1af613f12bc9cd11dfaf0b --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 42dcbbe5f18d3f50ec1af613f12bc9cd11dfaf0b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-07-11 09:13:13,163 truncating revision to first 12 chars 2016-07-11 09:13:13,163 Setting DEBUG logging. 2016-07-11 09:13:13,164 attempt 1/10 2016-07-11 09:13:13,164 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/42dcbbe5f18d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 09:13:13,962 unpacking tar archive at: fx-team-42dcbbe5f18d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.073060 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 09:13:14.179854) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:14.180185) ========= script_repo_revision: 42dcbbe5f18d3f50ec1af613f12bc9cd11dfaf0b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:14.180578) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:14.180880) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 09:13:14.201263) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-11 09:13:14.201983) ========= /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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:14 INFO - MultiFileLogger online at 20160711 09:13:14 in /builds/slave/test 09:13:14 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 09:13:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:13:14 INFO - {'append_to_log': False, 09:13:14 INFO - 'base_work_dir': '/builds/slave/test', 09:13:14 INFO - 'blob_upload_branch': 'Fx-Team', 09:13:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:13:14 INFO - 'branch': 'Fx-Team', 09:13:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:13:14 INFO - 'config_files': ('talos/mac_config.py',), 09:13:14 INFO - 'default_actions': ('clobber', 09:13:14 INFO - 'read-buildbot-config', 09:13:14 INFO - 'download-and-extract', 09:13:14 INFO - 'populate-webroot', 09:13:14 INFO - 'create-virtualenv', 09:13:14 INFO - 'install', 09:13:14 INFO - 'run-tests'), 09:13:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:13:14 INFO - 'download_minidump_stackwalk': True, 09:13:14 INFO - 'download_symbols': 'ondemand', 09:13:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:13:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:13:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:13:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:13:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:13:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:13:14 INFO - 'installer_path': 'installer.exe', 09:13:14 INFO - 'log_level': 'info', 09:13:14 INFO - 'log_name': 'talos', 09:13:14 INFO - 'log_to_console': True, 09:13:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:13:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:13:14 INFO - 'opt_config_files': (), 09:13:14 INFO - 'pip_index': False, 09:13:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:13:14 INFO - 'cmd': ('bash', 09:13:14 INFO - '-c', 09:13:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:13:14 INFO - 'enabled': True, 09:13:14 INFO - 'halt_on_failure': False, 09:13:14 INFO - 'name': 'check_screen_resolution'},), 09:13:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:13:14 INFO - 'cmd': ('bash', 09:13:14 INFO - '-c', 09:13:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:13:14 INFO - 'enabled': True, 09:13:14 INFO - 'halt_on_failure': False, 09:13:14 INFO - 'name': 'check_screen_resolution'},), 09:13:14 INFO - 'run_cmd_checks_enabled': True, 09:13:14 INFO - 'sps_profile': False, 09:13:14 INFO - 'sps_profile_interval': 0, 09:13:14 INFO - 'suite': 'dromaeojs', 09:13:14 INFO - 'system_bits': '32', 09:13:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:13:14 INFO - 'title': 't-yosemite-r7-0314', 09:13:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:13:14 INFO - 'use_talos_json': True, 09:13:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:13:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:13:14 INFO - 'work_dir': 'build'} 09:13:14 INFO - ##### 09:13:14 INFO - ##### Running clobber step. 09:13:14 INFO - ##### 09:13:14 INFO - Running pre-action listener: _resource_record_pre_action 09:13:14 INFO - Running main action method: clobber 09:13:14 INFO - rmtree: /builds/slave/test/build 09:13:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:13:16 INFO - Running post-action listener: _resource_record_post_action 09:13:16 INFO - ##### 09:13:16 INFO - ##### Finished clobber step (success) 09:13:16 INFO - ##### 09:13:16 INFO - ##### 09:13:16 INFO - ##### Running read-buildbot-config step. 09:13:16 INFO - ##### 09:13:16 INFO - Running pre-action listener: _resource_record_pre_action 09:13:16 INFO - Running main action method: read_buildbot_config 09:13:16 INFO - Using buildbot properties: 09:13:16 INFO - { 09:13:16 INFO - "project": "", 09:13:16 INFO - "product": "firefox", 09:13:16 INFO - "script_repo_revision": "production", 09:13:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:13:16 INFO - "repository": "", 09:13:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:13:16 INFO - "buildid": "20160711072628", 09:13:16 INFO - "pgo_build": "False", 09:13:16 INFO - "basedir": "/builds/slave/test", 09:13:16 INFO - "buildnumber": 96, 09:13:16 INFO - "slavename": "t-yosemite-r7-0314", 09:13:16 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:13:16 INFO - "platform": "yosemite_r7", 09:13:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:13:16 INFO - "branch": "fx-team", 09:13:16 INFO - "repo_path": "integration/fx-team", 09:13:16 INFO - "revision": "42dcbbe5f18d3f50ec1af613f12bc9cd11dfaf0b", 09:13:16 INFO - "stage_platform": "macosx64", 09:13:16 INFO - "builduid": "7c42e211bde54966b919f26307bdbb1a", 09:13:16 INFO - "slavebuilddir": "test" 09:13:16 INFO - } 09:13:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:13:16 INFO - Running post-action listener: _resource_record_post_action 09:13:16 INFO - ##### 09:13:16 INFO - ##### Finished read-buildbot-config step (success) 09:13:16 INFO - ##### 09:13:16 INFO - ##### 09:13:16 INFO - ##### Running download-and-extract step. 09:13:16 INFO - ##### 09:13:16 INFO - Running pre-action listener: _resource_record_pre_action 09:13:16 INFO - Running main action method: download_and_extract 09:13:16 INFO - mkdir: /builds/slave/test/build/tests 09:13:16 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')]} 09:13:16 INFO - https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:13:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:13:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:13:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:13:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:13:17 INFO - Downloaded 1199 bytes. 09:13:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:13:17 INFO - Using the following test package requirements: 09:13:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:13:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:13:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:13:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'jsshell-mac.zip'], 09:13:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:13:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:13:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:13:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:13:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:13:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:13:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:13:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:17 INFO - https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:13:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:13:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:13:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:13:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:13:18 INFO - Downloaded 18809494 bytes. 09:13:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:13:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:13:22 INFO - Return code: 0 09:13:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:22 INFO - https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:13:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:13:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:13:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:13:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:13:23 INFO - Downloaded 18809494 bytes. 09:13:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:13:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:13:26 INFO - Return code: 0 09:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:26 INFO - https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:13:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:13:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:13:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:13:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:13:27 INFO - Downloaded 14403333 bytes. 09:13:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:13:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:13:28 INFO - Return code: 0 09:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:28 INFO - https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:13:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:13:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:13:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:13:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:13:31 INFO - Downloaded 112384683 bytes. 09:13:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:13:31 INFO - mkdir: /builds/slave/test/properties 09:13:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:13:31 INFO - Writing to file /builds/slave/test/properties/build_url 09:13:31 INFO - Contents: 09:13:31 INFO - build_url:https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:13:32 INFO - Running post-action listener: _resource_record_post_action 09:13:32 INFO - Running post-action listener: set_extra_try_arguments 09:13:32 INFO - ##### 09:13:32 INFO - ##### Finished download-and-extract step (success) 09:13:32 INFO - ##### 09:13:32 INFO - ##### 09:13:32 INFO - ##### Running populate-webroot step. 09:13:32 INFO - ##### 09:13:32 INFO - Running pre-action listener: _resource_record_pre_action 09:13:32 INFO - Running main action method: populate_webroot 09:13:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:13:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:13:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:13:32 INFO - '--tppagecycles', 09:13:32 INFO - '7'], 09:13:32 INFO - 'tests': ['tsvgm']}}, 09:13:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:13:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:13:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:13:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:13:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['damp', 'tps']}, 09:13:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'tests': ['damp', 'tps']}, 09:13:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['dromaeo_dom']}, 09:13:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:13:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['basic_compositor_video']}, 09:13:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:13:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['a11yr', 09:13:32 INFO - 'ts_paint', 09:13:32 INFO - 'tpaint', 09:13:32 INFO - 'sessionrestore', 09:13:32 INFO - 'sessionrestore_no_auto_restore', 09:13:32 INFO - 'tabpaint']}, 09:13:32 INFO - 'other-e10s': {'tests': ['a11yr', 09:13:32 INFO - 'ts_paint', 09:13:32 INFO - 'tpaint', 09:13:32 INFO - 'sessionrestore', 09:13:32 INFO - 'sessionrestore_no_auto_restore', 09:13:32 INFO - 'tabpaint']}, 09:13:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:13:32 INFO - 'ts_paint', 09:13:32 INFO - 'tpaint', 09:13:32 INFO - 'sessionrestore', 09:13:32 INFO - 'sessionrestore_no_auto_restore', 09:13:32 INFO - 'tabpaint']}, 09:13:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:13:32 INFO - 'ts_paint', 09:13:32 INFO - 'tpaint', 09:13:32 INFO - 'sessionrestore', 09:13:32 INFO - 'sessionrestore_no_auto_restore', 09:13:32 INFO - 'tabpaint']}, 09:13:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['a11yr', 09:13:32 INFO - 'ts_paint', 09:13:32 INFO - 'tpaint', 09:13:32 INFO - 'sessionrestore', 09:13:32 INFO - 'sessionrestore_no_auto_restore', 09:13:32 INFO - 'tabpaint']}, 09:13:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['a11yr', 09:13:32 INFO - 'ts_paint', 09:13:32 INFO - 'tpaint', 09:13:32 INFO - 'sessionrestore', 09:13:32 INFO - 'sessionrestore_no_auto_restore', 09:13:32 INFO - 'tabpaint']}, 09:13:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['tsvgx', 09:13:32 INFO - 'tsvgr_opacity', 09:13:32 INFO - 'tart', 09:13:32 INFO - 'tscrollx', 09:13:32 INFO - 'cart']}, 09:13:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:13:32 INFO - 'tsvgr_opacity', 09:13:32 INFO - 'tart', 09:13:32 INFO - 'tscrollx', 09:13:32 INFO - 'cart']}, 09:13:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'talos_options': ['--disable-e10s'], 09:13:32 INFO - 'tests': ['tp5o']}, 09:13:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'tests': ['tp5o']}, 09:13:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'talos_options': ['--disable-e10s', 09:13:32 INFO - '--xperf_path', 09:13:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:32 INFO - 'tests': ['tp5n']}, 09:13:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:32 INFO - 'talos_options': ['--xperf_path', 09:13:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:32 INFO - 'tests': ['tp5n']}}, 09:13:32 INFO - 'talos.zip': {'path': '', 09:13:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:13:32 INFO - Running post-action listener: _resource_record_post_action 09:13:32 INFO - ##### 09:13:32 INFO - ##### Finished populate-webroot step (success) 09:13:32 INFO - ##### 09:13:32 INFO - ##### 09:13:32 INFO - ##### Running create-virtualenv step. 09:13:32 INFO - ##### 09:13:32 INFO - Running pre-action listener: _resource_record_pre_action 09:13:32 INFO - Running main action method: create_virtualenv 09:13:32 INFO - Creating virtualenv /builds/slave/test/build/venv 09:13:32 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 09:13:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:13:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:13:32 INFO - Using real prefix '/tools/python27' 09:13:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:13:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:35 INFO - Installing pip.................done. 09:13:35 INFO - Return code: 0 09:13:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:35 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 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:35 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 09:13:35 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 09:13:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:13:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:35 INFO - 'HOME': '/Users/cltbld', 09:13:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:35 INFO - 'LOGNAME': 'cltbld', 09:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:35 INFO - 'MOZ_NO_REMOTE': '1', 09:13:35 INFO - 'NO_EM_RESTART': '1', 09:13:35 INFO - 'PAGER': '/bin/cat', 09:13:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:35 INFO - 'PWD': '/builds/slave/test', 09:13:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:35 INFO - 'SHELL': '/bin/bash', 09:13:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:13:35 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:13:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:35 INFO - 'USER': 'cltbld', 09:13:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:35 INFO - 'XPC_FLAGS': '0x0', 09:13:35 INFO - 'XPC_SERVICE_NAME': '0', 09:13:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:36 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 09:13:36 INFO - Cleaning up... 09:13:36 INFO - Return code: 0 09:13:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:36 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 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:36 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 09:13:36 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 09:13:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:13:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:36 INFO - 'HOME': '/Users/cltbld', 09:13:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:36 INFO - 'LOGNAME': 'cltbld', 09:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:36 INFO - 'MOZ_NO_REMOTE': '1', 09:13:36 INFO - 'NO_EM_RESTART': '1', 09:13:36 INFO - 'PAGER': '/bin/cat', 09:13:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:36 INFO - 'PWD': '/builds/slave/test', 09:13:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:36 INFO - 'SHELL': '/bin/bash', 09:13:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:13:36 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:13:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:36 INFO - 'USER': 'cltbld', 09:13:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:36 INFO - 'XPC_FLAGS': '0x0', 09:13:36 INFO - 'XPC_SERVICE_NAME': '0', 09:13:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:36 INFO - Downloading/unpacking psutil>=3.1.1 09:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:39 INFO - Installing collected packages: psutil 09:13:39 INFO - Running setup.py install for psutil 09:13:39 INFO - building 'psutil._psutil_osx' extension 09:13:39 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 09:13:40 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 09:13:40 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 09:13:40 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 09:13:40 INFO - building 'psutil._psutil_posix' extension 09:13:40 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 09:13:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:13:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:13:40 INFO - ^ 09:13:40 INFO - 1 warning generated. 09:13:40 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 09:13:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:40 INFO - Successfully installed psutil 09:13:40 INFO - Cleaning up... 09:13:40 INFO - Return code: 0 09:13:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:13:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:13:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:40 INFO - 'HOME': '/Users/cltbld', 09:13:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:40 INFO - 'LOGNAME': 'cltbld', 09:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:40 INFO - 'MOZ_NO_REMOTE': '1', 09:13:40 INFO - 'NO_EM_RESTART': '1', 09:13:40 INFO - 'PAGER': '/bin/cat', 09:13:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:40 INFO - 'PWD': '/builds/slave/test', 09:13:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:40 INFO - 'SHELL': '/bin/bash', 09:13:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:13:40 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:13:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:40 INFO - 'USER': 'cltbld', 09:13:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:40 INFO - 'XPC_FLAGS': '0x0', 09:13:40 INFO - 'XPC_SERVICE_NAME': '0', 09:13:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:13:43 INFO - Installing collected packages: mozsystemmonitor 09:13:43 INFO - Running setup.py install for mozsystemmonitor 09:13:43 INFO - Successfully installed mozsystemmonitor 09:13:43 INFO - Cleaning up... 09:13:43 INFO - Return code: 0 09:13:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:13:43 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')]} 09:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:43 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')]} 09:13:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:43 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:43 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==2.5.1'] in /builds/slave/test/build 09:13:43 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==2.5.1 09:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:43 INFO - 'HOME': '/Users/cltbld', 09:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:43 INFO - 'LOGNAME': 'cltbld', 09:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:43 INFO - 'MOZ_NO_REMOTE': '1', 09:13:43 INFO - 'NO_EM_RESTART': '1', 09:13:43 INFO - 'PAGER': '/bin/cat', 09:13:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:43 INFO - 'PWD': '/builds/slave/test', 09:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:43 INFO - 'SHELL': '/bin/bash', 09:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:13:43 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:43 INFO - 'USER': 'cltbld', 09:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:43 INFO - 'XPC_FLAGS': '0x0', 09:13:43 INFO - 'XPC_SERVICE_NAME': '0', 09:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:43 INFO - Downloading/unpacking jsonschema==2.5.1 09:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:46 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:46 INFO - warning: no files found matching '*.txt' 09:13:46 INFO - no previously-included directories found matching 'build' 09:13:46 INFO - no previously-included directories found matching 'dist' 09:13:46 INFO - no previously-included directories found matching '.git*' 09:13:46 INFO - Installing collected packages: jsonschema, functools32 09:13:47 INFO - Running setup.py install for functools32 09:13:47 INFO - warning: no files found matching '*.txt' 09:13:47 INFO - no previously-included directories found matching 'build' 09:13:47 INFO - no previously-included directories found matching 'dist' 09:13:47 INFO - no previously-included directories found matching '.git*' 09:13:47 INFO - Successfully installed jsonschema functools32 09:13:47 INFO - Cleaning up... 09:13:47 INFO - Return code: 0 09:13:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:13:47 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')]} 09:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:47 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')]} 09:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:47 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 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:47 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 09:13:47 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 09:13:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:13:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:47 INFO - 'HOME': '/Users/cltbld', 09:13:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:47 INFO - 'LOGNAME': 'cltbld', 09:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:47 INFO - 'MOZ_NO_REMOTE': '1', 09:13:47 INFO - 'NO_EM_RESTART': '1', 09:13:47 INFO - 'PAGER': '/bin/cat', 09:13:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:47 INFO - 'PWD': '/builds/slave/test', 09:13:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:47 INFO - 'SHELL': '/bin/bash', 09:13:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:13:47 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:13:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:47 INFO - 'USER': 'cltbld', 09:13:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:47 INFO - 'XPC_FLAGS': '0x0', 09:13:47 INFO - 'XPC_SERVICE_NAME': '0', 09:13:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:47 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:47 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 09:13:47 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 09:13:47 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 09:13:47 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 09:13:47 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 09:13:47 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 09:13:49 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:50 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 09:13:50 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 09:13:50 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 09:13:50 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 09:13:50 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 09:13:50 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 09:13:51 INFO - Downloading docopt-0.6.1.tar.gz 09:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:51 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:51 INFO - Running setup.py install for blobuploader 09:13:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:51 INFO - Running setup.py install for requests 09:13:51 INFO - Running setup.py install for docopt 09:13:51 INFO - Successfully installed blobuploader requests docopt 09:13:51 INFO - Cleaning up... 09:13:52 INFO - Return code: 0 09:13:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:52 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 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:52 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 09:13:52 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 09:13:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:13:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:52 INFO - 'HOME': '/Users/cltbld', 09:13:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:52 INFO - 'LOGNAME': 'cltbld', 09:13:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:52 INFO - 'MOZ_NO_REMOTE': '1', 09:13:52 INFO - 'NO_EM_RESTART': '1', 09:13:52 INFO - 'PAGER': '/bin/cat', 09:13:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:52 INFO - 'PWD': '/builds/slave/test', 09:13:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:52 INFO - 'SHELL': '/bin/bash', 09:13:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:13:52 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:13:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:52 INFO - 'USER': 'cltbld', 09:13:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:52 INFO - 'XPC_FLAGS': '0x0', 09:13:52 INFO - 'XPC_SERVICE_NAME': '0', 09:13:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-kkcs5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-7nUA9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-S46KcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-bUeftN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-bL4K1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ajlKwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:52 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-s_qtPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-uoly5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-AaWOJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-eEHr4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-n5SdwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-0VUEHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-LeRNzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-hi2yw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-WCoMr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-e84S7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:53 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-hYgZ62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:54 INFO - Running setup.py install for manifestparser 09:13:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:54 INFO - Running setup.py install for mozcrash 09:13:54 INFO - Running setup.py install for mozdebug 09:13:54 INFO - Running setup.py install for mozdevice 09:13:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:54 INFO - Running setup.py install for mozfile 09:13:54 INFO - Running setup.py install for mozhttpd 09:13:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:54 INFO - Running setup.py install for mozinfo 09:13:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:55 INFO - Running setup.py install for mozInstall 09:13:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:55 INFO - Running setup.py install for mozleak 09:13:55 INFO - Running setup.py install for mozlog 09:13:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:55 INFO - Running setup.py install for moznetwork 09:13:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:55 INFO - Running setup.py install for mozprocess 09:13:55 INFO - Running setup.py install for mozprofile 09:13:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:56 INFO - Running setup.py install for mozrunner 09:13:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:56 INFO - Running setup.py install for mozscreenshot 09:13:56 INFO - Running setup.py install for moztest 09:13:56 INFO - Running setup.py install for mozversion 09:13:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:56 INFO - Cleaning up... 09:13:56 INFO - Return code: 0 09:13:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:56 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 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:13:56 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 09:13:56 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 09:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:56 INFO - 'HOME': '/Users/cltbld', 09:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:56 INFO - 'LOGNAME': 'cltbld', 09:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:56 INFO - 'MOZ_NO_REMOTE': '1', 09:13:56 INFO - 'NO_EM_RESTART': '1', 09:13:56 INFO - 'PAGER': '/bin/cat', 09:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:56 INFO - 'PWD': '/builds/slave/test', 09:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:56 INFO - 'SHELL': '/bin/bash', 09:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:13:56 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:56 INFO - 'USER': 'cltbld', 09:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:56 INFO - 'XPC_FLAGS': '0x0', 09:13:56 INFO - 'XPC_SERVICE_NAME': '0', 09:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:56 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-XPpccv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-vv1k1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-FVCW6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ExXQh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-1iBnRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-x5BWAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-8jXYCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-4IfXwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Y6knSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:57 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-5FbXak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:57 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)) 09:13:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-9jnwyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:58 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)) 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-20Xrvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-5UT2Z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:58 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)) 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-V8V1YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-3xVSQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:58 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)) 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Tf5JK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:58 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)) 09:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-mfpAWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:58 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)) 09:13:58 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)) 09:13:58 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)) 09:13:58 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)) 09:13:58 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)) 09:13:58 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)) 09:13:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:14:01 INFO - Downloading blessings-1.6.tar.gz 09:14:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:14:01 INFO - Installing collected packages: blessings 09:14:01 INFO - Running setup.py install for blessings 09:14:01 INFO - Successfully installed blessings 09:14:01 INFO - Cleaning up... 09:14:01 INFO - Return code: 0 09:14:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:14:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:01 INFO - Reading from file tmpfile_stdout 09:14:01 INFO - Current package versions: 09:14:01 INFO - blessings == 1.6 09:14:01 INFO - blobuploader == 1.2.4 09:14:01 INFO - docopt == 0.6.1 09:14:01 INFO - functools32 == 3.2.3-2 09:14:01 INFO - jsonschema == 2.5.1 09:14:01 INFO - manifestparser == 1.1 09:14:01 INFO - mozInstall == 1.12 09:14:01 INFO - mozcrash == 0.17 09:14:01 INFO - mozdebug == 0.1 09:14:01 INFO - mozdevice == 0.48 09:14:01 INFO - mozfile == 1.2 09:14:01 INFO - mozhttpd == 0.7 09:14:01 INFO - mozinfo == 0.9 09:14:01 INFO - mozleak == 0.1 09:14:01 INFO - mozlog == 3.2 09:14:01 INFO - moznetwork == 0.27 09:14:01 INFO - mozprocess == 0.23 09:14:01 INFO - mozprofile == 0.28 09:14:01 INFO - mozrunner == 6.12 09:14:01 INFO - mozscreenshot == 0.1 09:14:01 INFO - mozsystemmonitor == 0.3 09:14:01 INFO - moztest == 0.7 09:14:01 INFO - mozversion == 1.4 09:14:01 INFO - psutil == 3.1.1 09:14:01 INFO - requests == 1.2.3 09:14:01 INFO - wsgiref == 0.1.2 09:14:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:01 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 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:14:01 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 09:14:01 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 09:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:01 INFO - 'HOME': '/Users/cltbld', 09:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:01 INFO - 'LOGNAME': 'cltbld', 09:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:01 INFO - 'MOZ_NO_REMOTE': '1', 09:14:01 INFO - 'NO_EM_RESTART': '1', 09:14:01 INFO - 'PAGER': '/bin/cat', 09:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:01 INFO - 'PWD': '/builds/slave/test', 09:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:01 INFO - 'SHELL': '/bin/bash', 09:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:14:01 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:01 INFO - 'USER': 'cltbld', 09:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:01 INFO - 'XPC_FLAGS': '0x0', 09:14:01 INFO - 'XPC_SERVICE_NAME': '0', 09:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 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)) 09:14:02 INFO - Cleaning up... 09:14:02 INFO - Return code: 0 09:14:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:14:02 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')]} 09:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:14:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:02 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')]} 09:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:14:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:14:02 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 0x108d3fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcbe85a4100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, '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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 09:14:02 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 09:14:02 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 09:14:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:14:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:02 INFO - 'HOME': '/Users/cltbld', 09:14:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:02 INFO - 'LOGNAME': 'cltbld', 09:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:02 INFO - 'MOZ_NO_REMOTE': '1', 09:14:02 INFO - 'NO_EM_RESTART': '1', 09:14:02 INFO - 'PAGER': '/bin/cat', 09:14:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:02 INFO - 'PWD': '/builds/slave/test', 09:14:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:02 INFO - 'SHELL': '/bin/bash', 09:14:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:14:02 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:14:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:02 INFO - 'USER': 'cltbld', 09:14:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:02 INFO - 'XPC_FLAGS': '0x0', 09:14:02 INFO - 'XPC_SERVICE_NAME': '0', 09:14:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:14:02 INFO - Cleaning up... 09:14:02 INFO - Return code: 0 09:14:02 INFO - Running post-action listener: _resource_record_post_action 09:14:02 INFO - Running post-action listener: _start_resource_monitoring 09:14:02 INFO - Starting resource monitoring. 09:14:02 INFO - ##### 09:14:02 INFO - ##### Finished create-virtualenv step (success) 09:14:02 INFO - ##### 09:14:02 INFO - ##### 09:14:02 INFO - ##### Running install step. 09:14:02 INFO - ##### 09:14:02 INFO - Running pre-action listener: _resource_record_pre_action 09:14:02 INFO - Running main action method: install 09:14:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:14:02 INFO - Reading from file tmpfile_stdout 09:14:02 INFO - Detecting whether we're running mozinstall >=1.0... 09:14:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:14:02 INFO - Reading from file tmpfile_stdout 09:14:02 INFO - Output received: 09:14:02 INFO - Usage: mozinstall [options] installer 09:14:02 INFO - Options: 09:14:02 INFO - -h, --help show this help message and exit 09:14:02 INFO - -d DEST, --destination=DEST 09:14:02 INFO - Directory to install application into. [default: 09:14:02 INFO - "/builds/slave/test"] 09:14:02 INFO - --app=APP Application being installed. [default: firefox] 09:14:02 INFO - mkdir: /builds/slave/test/build/application 09:14:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:14:37 INFO - Reading from file tmpfile_stdout 09:14:37 INFO - Output received: 09:14:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:14:37 INFO - Running post-action listener: _resource_record_post_action 09:14:37 INFO - ##### 09:14:37 INFO - ##### Finished install step (success) 09:14:37 INFO - ##### 09:14:37 INFO - ##### 09:14:37 INFO - ##### Running run-tests step. 09:14:37 INFO - ##### 09:14:37 INFO - Running pre-action listener: _resource_record_pre_action 09:14:37 INFO - Running main action method: run_tests 09:14:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:37 INFO - 2016-07-11 09:14:37.329 screenresolution[1661:7651] starting screenresolution argv=screenresolution get 09:14:37 INFO - 2016-07-11 09:14:37.332 screenresolution[1661:7651] Display 0: 1600x1200x32@60 09:14:37 INFO - 2016-07-11 09:14:37.338 screenresolution[1662:7653] starting screenresolution argv=screenresolution list 09:14:37 INFO - Available Modes on Display 0 09:14:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:37 INFO - Intel Iris: 09:14:37 INFO - Chipset Model: Intel Iris 09:14:37 INFO - Type: GPU 09:14:37 INFO - Bus: Built-In 09:14:37 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:37 INFO - Vendor: Intel (0x8086) 09:14:37 INFO - Device ID: 0x0a2e 09:14:37 INFO - Revision ID: 0x0009 09:14:37 INFO - Displays: 09:14:37 INFO - Display: 09:14:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:37 INFO - Display Serial Number: 1600x1200 60 09:14:37 INFO - Main Display: Yes 09:14:37 INFO - Mirror: Off 09:14:37 INFO - Online: Yes 09:14:37 INFO - Rotation: Supported 09:14:37 INFO - Return code: 0 09:14:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:14:37 INFO - Python 2.7.3 09:14:37 INFO - Return code: 0 09:14:37 INFO - grabbing minidump binary from tooltool 09:14:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:37 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 0x108ca3d30>, '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 0x108a08db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108c9bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:14:37 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 09:14:37 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 09:14:37 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 09:14:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:37 INFO - Return code: 0 09:14:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:14:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:14:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:14:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:14:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:14:37 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/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 09:14:37 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/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0314 --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 09:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pyp3HuSQCN/Render', 09:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:37 INFO - 'HOME': '/Users/cltbld', 09:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:37 INFO - 'LOGNAME': 'cltbld', 09:14:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:37 INFO - 'MOZ_NO_REMOTE': '1', 09:14:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:37 INFO - 'NO_EM_RESTART': '1', 09:14:37 INFO - 'PAGER': '/bin/cat', 09:14:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:37 INFO - 'PWD': '/builds/slave/test', 09:14:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:37 INFO - 'SHELL': '/bin/bash', 09:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KGyM6Qfgpv/Listeners', 09:14:37 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 09:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:37 INFO - 'USER': 'cltbld', 09:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:37 INFO - 'XPC_FLAGS': '0x0', 09:14:37 INFO - 'XPC_SERVICE_NAME': '0', 09:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:37 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/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 09:14:37 INFO - mozversion application_buildid: 20160711072628 09:14:37 INFO - mozversion application_changeset: 42dcbbe5f18d3f50ec1af613f12bc9cd11dfaf0b 09:14:37 INFO - mozversion application_display_name: Nightly 09:14:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:37 INFO - mozversion application_name: Firefox 09:14:37 INFO - mozversion application_remotingname: firefox 09:14:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:14:37 INFO - mozversion application_vendor: Mozilla 09:14:37 INFO - mozversion application_version: 50.0a1 09:14:37 INFO - mozversion platform_buildid: 20160711072628 09:14:37 INFO - mozversion platform_changeset: 42dcbbe5f18d3f50ec1af613f12bc9cd11dfaf0b 09:14:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:14:37 INFO - mozversion platform_version: 50.0a1 09:14:37 INFO - using testdate: 1468253677 09:14:37 INFO - actual date: 1468253677 09:14:37 INFO - starting webserver on 'localhost:49277' 09:14:37 INFO - SUITE-START | Running 2 tests 09:14:37 INFO - TEST-START | dromaeo_css 09:14:37 INFO - operating with platform_type : mac_ 09:14:37 INFO - Initialising browser for dromaeo_css test... 09:14:37 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpsEej18/profile http://localhost:49277/getInfo.html) 09:14:40 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 09:14:40 INFO - PROCESS | 1673 | colorDepth:24 09:14:40 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 09:14:40 INFO - PROCESS | 1673 | __metrics 09:14:40 INFO - PROCESS | 1673 | 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" 09:14:40 INFO - PROCESS | 1673 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:14:40 INFO - PROCESS | 1673 | ************************* 09:14:40 INFO - PROCESS | 1673 | A coding exception was thrown in a Promise resolution callback. 09:14:40 INFO - PROCESS | 1673 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 09:14:40 INFO - PROCESS | 1673 | 09:14:40 ERROR - PROCESS | 1673 | Full message: TypeError: win is null 09:14:40 INFO - PROCESS | 1673 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 09:14:40 INFO - PROCESS | 1673 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 09:14:40 INFO - PROCESS | 1673 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 09:14:40 INFO - PROCESS | 1673 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 09:14:40 INFO - PROCESS | 1673 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 09:14:40 INFO - PROCESS | 1673 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 09:14:40 INFO - PROCESS | 1673 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 09:14:40 INFO - PROCESS | 1673 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 09:14:40 INFO - PROCESS | 1673 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 09:14:40 INFO - PROCESS | 1673 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 09:14:40 INFO - PROCESS | 1673 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 09:14:40 INFO - PROCESS | 1673 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 09:14:40 INFO - PROCESS | 1673 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 09:14:40 INFO - PROCESS | 1673 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 09:14:40 INFO - PROCESS | 1673 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 09:14:40 INFO - PROCESS | 1673 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 09:14:40 INFO - PROCESS | 1673 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 09:14:40 INFO - PROCESS | 1673 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1515:5 09:14:40 INFO - PROCESS | 1673 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 09:14:40 INFO - PROCESS | 1673 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 09:14:40 INFO - PROCESS | 1673 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3726:34 09:14:40 INFO - PROCESS | 1673 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2715:25 09:14:40 INFO - PROCESS | 1673 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 09:14:40 INFO - PROCESS | 1673 | _startProvider@resource://gre/modules/AddonManager.jsm:779:5 09:14:40 INFO - PROCESS | 1673 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:963:9 09:14:40 INFO - PROCESS | 1673 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2985:5 09:14:40 INFO - PROCESS | 1673 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 09:14:40 INFO - PROCESS | 1673 | 09:14:40 INFO - PROCESS | 1673 | ************************* 09:14:41 INFO - TEST-INFO | 1673: exit 0 09:14:41 INFO - Browser initialized. 09:14:41 INFO - Running cycle 1/1 for dromaeo_css test... 09:14:41 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpsEej18/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:53 INFO - PROCESS | 1675 | RSS: Main: 263446528 09:14:53 INFO - PROCESS | 1675 | 09:15:44 INFO - PROCESS | 1675 | 1468253744312 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] 09:17:56 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 09:17:57 INFO - PROCESS | 1675 | RSS: Main: 396722176 09:17:57 INFO - PROCESS | 1675 | 09:21:00 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 09:21:01 INFO - PROCESS | 1675 | RSS: Main: 395218944 09:21:01 INFO - PROCESS | 1675 | 09:24:03 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 09:24:04 INFO - PROCESS | 1675 | RSS: Main: 387244032 09:24:04 INFO - PROCESS | 1675 | 09:27:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 09:27:07 INFO - PROCESS | 1675 | RSS: Main: 391147520 09:27:07 INFO - PROCESS | 1675 | 09:30:10 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 09:30:11 INFO - PROCESS | 1675 | RSS: Main: 354222080 09:30:11 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 09:33:14 INFO - PROCESS | 1675 | RSS: Main: 400621568 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | __start_tp_report 09:33:14 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 09:33:14 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 09:33:14 INFO - PROCESS | 1675 | |i|pagename|runs| 09:33:14 INFO - PROCESS | 1675 | |0;dojo.html;3364.3103163851415;3373.6289008209096;3403.2002479417347;3405.1316914186887;3456.239627281997;5685.914711279334;5778.76884924603;5794.246747922774;5840.474357307841;5895.793647222348;5781.682007489587;5822.155787410823;5830.941690583093;5832.125181222818;5845.620034697742;5257.369115706101;5292.994331077102;5325.147976323789;5342.839714808542;5389.51494365507;5360.865978350534;5389.164679474668;5401.4328495508;5407.459254074581;5407.810726624569;5290.788368465257;5312.17661262503;5323.307969963899;5326.174442961347;5398;5401.837944861641;5413.783448662049;5422.0511410503395;5439.564834813205;5451.781928722847;5142.871428214325;5238.214267859814;5245.449227831061;5324.0150572144275;5336.359636843604;5736.827895163152;5748.137779333092;5756.1653560233535;5842.941570584282;5869.1196320551835;6269.310375858652;6278.40355166263;6295.181626388586;6301.243850737895;6311.93688063118;6215.906761398583;6218.191635087455;6272.686365681698;6298.055291706235;6315.431611155018;5909;5989.401059894063;6028.577999540078;6033.637981721111;6044.032954727222;6356.586821856565;6361.300256971729;6375.904361434582;6380.521460890452;6382.808515744535;6120.14317995481;6152.107944348045;6200.658963757037;6276.152719382825;6291.5281353898645;6265.624062556261;6283;6290.24517057956;6300.653464059521;6311.96844015777;6243.750249989949;6288.213973253343;6298.370162983757;6311.747530098744;6314.273858506239;6148.385161483816;6178.969105154445;6196.8760624787255;6239.064140378889;6328.240611126695;4872.561469467765;5011.599072074226;5012.172991456368;5028.346314979102;5065.924011139836;4536.818527258939;4537.137943790669;4542.454905411372;4585.426821647294;4610.170169369479;5823.941760582425;5871.853203669857;5890.087036509348;5893.351731309553;5947.286325640951;5299.470052994747;6124.045297608036;6217.253929528395;6281.937180628227;6303.936960630427;5835.766569337179;5908.881822363529;5913.053911374245;5922;5975.970120149372;5540.63985840919;5547.916781248285;5558.249636299129;5569.637973531708;5573.749181286852;6141.792871882774;6265.937340626718;6296.590721603081;6322.46259067966;6389.8722025560155;6252.437280644764;6253.812385628438;6257.7184026719815;6261.373862613702;6272.937270627235;6362.713677884413;6382.32985536512;6389.041643753289;6405.903911441425;6422.293547709842;6112.929053818819;6192.81421557354;6212.347703491249;6240.812775616739;6257.1240026395435;5472.179173124063;5950.613210141327;6252.937470625236;6259.96870015647;6281.309056003927;6183.103449999815;6204.875902481835;6222.128901953821;6244.500439964701;6277.937220627735;6100.816975490742;6108.402780883668;6158.3841615838055;6194.783182588588;6264.373562643699;6112.663803490851;6126.376510224705;6141.785037523665;6149.2620885494025;6153.569250152446;6099.634021958696;6214.25428948529;6232.439080482778;6233.158523599437;6235.064740288993;5918.822435326947;6070.119832624333;6112;6131.938680613315;6133.264008318852 09:33:14 INFO - PROCESS | 1675 | |1;ext.html;516.5764073459762;522.4409881426875;523.2125650895405;539.2585195356385;559.9776008959643;30174.396512069747;30983.535246971285;31699.52450713241;31774;31794.99999999997;32502.537385817635;43540.564594354044;44612.55387446124;44641.77679111608;47581.28628070582;8565.871511927326;9746.853797193031;9849.901500985006;9861.11249987501;9884.950575247114;8647;9729.902700973005;9845.753856153618;9848.40909545426;9851.950740246324;8693.47839129651;11777;11966.880331196708;12044.036477081856;12225.38873056348;12106.334151621657;12142.967847732865;12167.51329946801;12170.965467935148;12212.572559960403;8588.914110858874;10095.697129086138;11468.885311146949;12007.3395963222;12032.939835300855;42437.78781106075;42846.357304640456;42909;42920.14159716788;42955.35566966467;3542.3269578780205;3605.405108157177;3759.9436008459893;3899.78551179685;3938.330483817729;32518.18704532382;33489.16277093068;33498.6650133498;33665.96008478974;33811.4928276076;3262.151840521458;3290.3748287825238;3298.752593555493;3392.8133952632597;3417.7607567470286;547229;549761;551657.2417137888;552548.2372588111;655427.7828886886;63585;64409.35590644034;64472.677636611515;69177;69285;69161.96257056149;69359.95960060603;69800.65099674469;69813.65093174501;69834;17009.23458444373;17145.399911003056;17146.657066858592;17152;17153;12994.415251313714;13174;13177.143485673401;13184.208947463183;13204.339783010812;17357;18456.405882602387;19916.60166796656;20142.194312227348;20613.381598552067;8031.397645176518;8056.274935255855;8078.676852925817;8092.878606820902;8100.392470564731;922.2437601166979;992.4343124419012;1005.1456262177092;1067.7917806027906;1103.3049179017169;30446;31376.8228242342;31940.840295798836;31943.337583678785;32359.67640323614;1046.6546039806844;1065.2383545764815;1066.8239740442896;1078.8671894510733;1155.9537618495167;15924.778628843473;16133.67732645364;16193.514194574182;16380.590485237964;16604.086775227235;179673;182309;182560;183394;184042;62221.688891555255;62786.058209125986;62927;63296.36703632997;63440.68279658665;1378.4899587152818;1426.179946530749;1446.11064195519;1454.1856560326462;1459.5621313605664;30769.69230307758;30906;31023.224419388793;31314.691256215563;31937.041888743373;54567;54685.179722304994;55062.724686376314;55160;55164;595609;607442;610931;611042;611791;24417.145399910918;24856.130035448674;27705.584416234175;28077.57883631706;28193.295167620974;42428.0295016312;50525;51341.48658513367;54249.45750542602;54404;39761.60238397579;42402;42428.363574547024;42428.787856060524;43440.56559434365;32876.835615822725;41773.79113104415;41922.161556768086;42402.15195696131;42613;39290.2141957165;39476.80261598789;39773.00567485836;40357;40759.1848163041;34973.65026349704;35466.93599192028;36572;36584.634153658124;36697.44953825641;22115.889420552794;26254.081107161157;26577.069802556816;26786.59820102661;26835.792389342918 09:33:14 INFO - PROCESS | 1675 | |2;jquery.html;342.40250762419583;381.2394273424518;384.552161113408;387.90302424393883;407.90210349516127;15006;16118.274677639505;19370.612587748255;21480.248191313287;21549.56900861982;21381.0378532966;21892.45268868279;22294.331170064885;22424;22446;5957.910631340534;6164.198654174951;6167.167432396636;6192.628442293454;6193;4166.187593419285;6556.508261880354;6858.314168583127;6885.758998435056;6947.513674042819;5690.516306113986;6047.304559975615;6827.52207345486;6829.829254268659;6872.896906546406;5961.940380596182;6037.064255040454;6037.909431358533;6081.787137450212;6115.5719099663265;5847.8245652630485;5958.5828991970575;6003.759849606011;6005.759769609211;6010.9699451502465;13362.198268103944;13640.317984100855;13857.960652950967;13867.306634668226;13874.4450221991;2412.300432874464;2452.583060879637;2555.348386161517;2690.5426077566863;2732;10144.644937427229;10672;12429.627111186679;15028.474003409938;16173.458226438419;1800.7208882623215;1863.8322550970342;1876.2307453943954;1945.4260993007097;2132.9786702132933;121433;125460;126900;127217;131556.3422182883;11360.659180224606;11586.594469193538;11687.006604438548;11695.707607309716;11809.645710628694;14433.20617366055;14674.779878301833;14776.630584235265;14785.964982451125;14794.630134246514;6984.965075174693;8834;8879.822403551892;8950.328725345626;8950.597223124974;8853.096584234292;8869.157430044135;8890.422122562013;8987.640494380283;9032.548372581345;4410.911781764346;4579.793909274091;4613.685099746556;4659;4660.487346391894;1889.1121173048646;1930.0952067138867;1938.7479627648597;1943.3975467605087;1962.1646720488757;728.1153398620595;822.3914303415429;847.4745657692271;876.6581033396981;884.1291328041874;21999.790011549198;22510.66234006491;22517.549649007255;22610.321690349316;22753.317400478012;887.3300658003295;925.4354843545432;940.4404379394249;950.011987532958;1005.3113617172261;11554.653360399201;11867.188864220287;11942.761144777056;11950.924416802529;11980.161388702882;608763;626777;635098;637768;648688;81112.59443702862;84171;119241;148893;160232;960.3229723045237;990.5740531571349;1005.8843233028296;1007.536533194724;1008.2035192198229;15965.840341596435;20095.799042009392;20193.091310891337;20760.89619551953;20836.68744968857;38275.42586861143;51010.97978040492;58409.5397615046;59561;60008.69995650168;75659;78005.82991255022;80509;87965.56017219873;88730.79595846302;7152.964235178791;7457.2542745724995;7461.925380746123;7710.11333696632;8222.465539739898;11933.343666098448;12903.838654521138;13152.276624785729;13186.011049171542;13352.465901363837;10782.568697252022;11412.225533484194;11568.305901645928;11581;11624.709382265512;11088.889111108785;11331.716707082389;11397.487113080097;11453.942730286295;11554.075673946232;5793.478586927196;8302.54336011525;11640.417979101181;11713.590024349107;11879.940600297288;6811.523193376416;7039.54242974205;7184.1738200105565;7474.626268686653;7661.195574464601;10747.301425407324;11439.256448330832;11442.485088170886;11445.141614378788;11530.481128349413 09:33:14 INFO - PROCESS | 1675 | |3;mootools.html;1541.2062787664352;1572.3946280681937;1776.4315419065904;1912.4836294200556;1918.0697361779535;8574.528400937947;8857.91142088579;9036.909630903689;9039.954800225998;9042.909570904289;6240.344763799803;8105.756827295176;8221.807837863522;8514.48913065215;8743.038265790774;2354.0348457132536;2375.0381095656285;2375.726791418983;2376.869272190029;2381.047580967618;2357.351728274721;2358.445765245166;2407.9759202408013;2408.2534414331612;2417.867017314047;2097.863638863477;2177.858439201455;2310.688057112285;2386.940326491843;2387.546366190427;2365.089440565386;2365.8580485170946;2379.9171377023413;2382.213869423086;2389.0682633772844;2384.225126833786;2384.332386931662;2385.1413491143176;2387.1286980252257;2387.3196139100446;7697.769066928001;8958.328125390623;9014.323925705598;9029.819403611957;9042.593083311202;5260.842174734764;5371.5165635092635;5416;5418.295621569171;5429.592780541475;3517.8944631661093;3586.8923932282073;3701.222743223926;3703.203811180609;3705.3886108792017;1049.1134990932649;1067.813132701774;1077.138289368502;1093.5352475198008;1096.4517741129434;11900.226485278428;11912.880871191264;11929.821052684216;11931.761364772656;11946;4045.85569648087;4616.76916154191;4718.528147185254;4829.468758436569;4845.53967373099;10036.946120657372;10064.094231519199;10079.445630490247;10081.445520496443;10096.495175241356;5420.376656684453;5423.159410291411;5426.511613954763;5431.131019036935;5447.618666693373;4780.1873681474235;4948.356713627206;4959.404871415453;4975.328330675385;4998.275250088716;3814.923701525954;3833.769973801581;3844;3881.1849511602313;3888.4944957155953;1089.4008295437477;1156.9942150289362;1160.9419529023685;1161.3380373187201;1170.3329102411544;682.66208226927;844.1727107434748;948.6158105967095;967.5452537307455;985.4678473624306;5987.281526216795;6026.487748541335;6052.394760524;6164.229471316085;6205.47253483459;966.40566051879;976.1409959235828;984.3806937587628;996.5415908681942;1014.5941623350719;4007.919841603152;4078.2455245779624;4082.2039702258367;4119.938200926988;4142.647874930664;10533.631322903815;10932.289401188897;10986.725331866768;10996.615118470816;11076.169287303323;9941.751456213653;10078.34490758113;10915.945420273007;10942.17933654979;10956.178286628534;8029.759107226792;8277.503349799032;8357.414980951275;8389.874151887727;8391.244787969112;5954.672493012926;6038.8792224154395;6128.32588415265;6177.980633195472;6219.596768962892;9497;10071.34536255141;10072.043155900177;10081.092701656886;10191.592336306465;10297.279190456597;10320.277580569287;10342.120919721607;10348;10348.172146228133;7984.481008734414;8246.05170405411;8262.83474330498;8314.41799074059;8399.95800020996;8449.704260350858;8468.49189048659;8538.70114545988;8554.743357699279;8560;7228.545520313611;7314.926850731637;7416.814579635552;7498.175200728025;7579.317861392501;7387.4459415545125;7437.0331856858265;7491.250874912465;7501.1373692023935;7576.810579735551;5993.940060599338;6950.826229344307;6986.196587392312;7052;7058.470614703815;4689.6248300136585;4870.829520966749;4918.114739346952;5051.3433253676785;5146.408163061295;4631.976840115913;5070.366204224472;5099.534095722211;5125.359330083739;5329.626926115135 09:33:14 INFO - PROCESS | 1675 | |4;prototype.html;297.46307914214833;301.5100461749657;312.3238189320122;315.0485533688263;316.0439669998255;4669.463011753649;5170.9482905170935;5372.7045012524295;5378.973105134473;5386.272853164822;4350.41269428627;5189.351331083614;5305.912287980965;5354.5716342692585;5391.81128660497;4115.835366585333;4119.196756632457;4125.443065186191;4136.8965775855695;4142.357934520154;3390.2541440883087;4002.459667944819;4136.565660605623;4169.770662613555;4194.706370554062;3271.8364081795935;4040.5353384360733;4051.027753339207;4058.6550143237714;4068.7151899367054;3594.0655429588364;3977.622125898035;4042.9191416171807;4062.6343629073526;4072.4705788247634;3512.139525816166;3522.20750331176;3525.360707271131;3535.257595904863;3562.8931132066077;4909.3126962225315;5204.992156039866;5216.713080780555;5246.239295302198;5259.552938000236;3967.444557761916;4473.724988378297;4599.5400459953735;4622.583967442913;4658.673892827504;5620.185073164409;5622.690752008638;5623.353314368859;5627.465390787868;5627.662340259557;3739.0461535387285;3832.4826148470156;3855.265130691172;3867.59390264021;3879;5193.68837869729;5602.495775380195;5917.437843404869;5975.671338076404;5976.133460648225;3947.684185265172;3954.694950666273;3963.207358528306;3966.8809935701975;3976.304146774303;3339.814365900119;3519.8768043118366;3766.6233376662663;3875.689944804465;3938.3107956107565;3958.960410395917;4021.8190181441905;4592.632589392907;4606.262997920317;4628.791704373311;4599.8730311074005;4687.718736875799;4924;5571.192177134293;5689;1030.5311083457009;1141.4007645985923;1322.5833862333334;1328.4951718346965;1339.1429485129524;743.8772602520523;879.7228872905015;887.7203680840514;903.8960519540337;908.0238743350924;4760.381150450488;5138.511841375063;5226.817061402832;5228.320318358589;5240.34495688039;5632.915506267409;5722.570807189478;5738.167965644958;5752.482276595127;5752.9712351437975;4502.369668246448;5102.668326558762;5153.974230128825;5274.709890955957;5325.227841962894;2312.8727919964226;2592.74072592743;2996.4230761545055;3049.6645369009393;3056.9583264651815;5058.570021548136;5874.794382196603;5968.8810472282585;5994.741104368131;6029.336772954972;4403.4495688039;5703.944770217523;6010;6014.939850601526;6059.394060593993;5875.794347197828;5906.143609176731;5944.970275148597;5955.225820643375;6039.8792024159275;5526.281583394133;5745.626534275259;5818.912716309174;5916.615419997686;5922.822315330547;5225.733916302311;5633.971830140823;5729.312482502127;5734.7132643368495;5789.91315130265;5986.371430999682;5995.2206213192;6034;6047.818565443044;6054.788082417094;3746.2507498499863;3864.787436691009;3895.7857317847793;3940.842366305316;3941.349677303213;4490.618297444623;4751.598278507918;5333.866653333697;5469;5867.6772777497645;4417.2711502603;5397.7840886365675;5573.7770489179975;5707;5713;4894.730789806595;5672.801451949162;5679.460451257124;5681.857953551194;5686.232358631697;5287.312649355559;5519.172124181405;5526.309211348582;5564.33228012641;5567.582431317586;5576.888462230814;5609.24275222856;5659.764393064799;5669.20631111653;5669.744861481161;5248.947510524846;5327.866803329948;5412.860540886355;5547.694876781816;5594.664320140804 09:33:14 INFO - PROCESS | 1675 | |5;yui.html;740.7481456304857;761.8247803005308;772.1082150116617;847.9194476524732;849.2526972186478;17366.913165434173;18037.549061273457;18046.5488362791;18048;18070.915745055274;17173.141342932864;17318.48044558662;17377.957322560622;17391.652166956654;17415.30338786447;696.9128858892639;874.3092956564323;884.6859364925456;885.4554449013874;887.5562218890555;717.8600172966273;866.7313132928781;896.6592694775976;903.0563061600643;904.9502277374742;910.3309067835136;920.3143657974819;922.1470140120396;923.1138107416888;923.8198551282503;725.6263024542365;908.0737647599477;916.8945571259324;917.0645941140067;917.9495127767971;680.1769858471206;684.165318311659;685.8971154326841;686.5914780705472;691.0808624529363;15698.68602627941;15778.76331855023;15786.842131578653;15802.209889505593;15869.603259918478;13815.309234538232;14021.158730476101;14095;14259.073160244654;14262.57212283633;13318.86681133186;13395.86604133956;13447.260400677958;13532.391042403115;13808.447662093504;4625.814967401334;4655.138799322136;4704.694194877322;4795.088933102699;4834.758262086882;122907;123023;124641.37679311636;124897;127316.72683273141;11092.223544351818;11093.223474356879;11137.852520572016;11219.382933938712;11545.769084618429;22258.3322500322;22276.55446891053;22278.665820012713;22294.885255737147;22332.21837235689;6546.803595892131;8016.358691304682;8030.317423018992;8055.637496312563;8169.42814003026;4456.286994080996;4527.648221245522;4547.749873756975;4626.236670949255;4753.572178503951;3454.775439596418;3552.3250582389264;3556.715462762973;3557.768745031565;3561.982190089033;629.9433051025429;646.5894157210112;648.2350826025336;658.0392626764881;662.8144119646506;594.3076316091791;602.7679343452714;613.7667686279275;634.7968650032038;648.2253706820399;8170.591470426455;8269.917300826915;8453.873191902127;8794.516301603346;9027.322950778587;532.3106576982816;613.7452957022875;624.7938180400457;626.5363630913182;627.075064280183;4135.937960930589;4452.821887124479;4927.531884470969;5010.624203184776;5122.206305855319;135207.64792351951;164879.1756041212;165198.17400912917;170954.14522927307;171663.14168429078;134399;136068.63931360558;137215;139621;142199.28900355432;125207;127696.36151819181;129438;129611;132585;10079.143272821599;10088.495575221357;10090.646827361006;10091;10092.444915529717;46769.830754231414;53871.73064134654;60181.495462613784;62352;68289;62006;62137.68931155315;90635;103802.92394151924;104773.47613261885;12542;13640.92177245601;13655.658608534468;13795.79306310386;14084.15495070299;15409.69180616359;15436.614084647974;15625;15856.437240934158;15901.443449479213;14342.924280679199;14494.782578261545;14559.490417835324;14596.956426100141;14638.634034149232;13971.790423143864;14170.504032358818;14540.818877168509;14837.40650373973;14974;14015.159090454605;14061.789073164115;14286.214258215898;14786.778198326818;14856.851431485547;10907;11274.718132046764;11359.943200283946;11386.43067846621;11932.70168245766;10179.98086582155;11455.699674361102;11632.767344653228;11855.703607409883;11881.821772673244 09:33:14 INFO - PROCESS | 1675 | __end_tp_report 09:33:14 INFO - PROCESS | 1675 | __start_cc_report 09:33:14 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1403 09:33:14 INFO - PROCESS | 1675 | __end_cc_report 09:33:14 INFO - PROCESS | 1675 | __startTimestamp1468254794815__endTimestamp 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | ------- Summary: start ------- 09:33:14 INFO - PROCESS | 1675 | Number of tests: 6 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5831.02 Median:6108.40 stddev:622.82 (10.2%) stddev-sans-first:593.91 09:33:14 INFO - PROCESS | 1675 | Values: 3364.3 3373.6 3403.2 3405.1 3456.2 5685.9 5778.8 5794.2 5840.5 5895.8 5781.7 5822.2 5830.9 5832.1 5845.6 5257.4 5293.0 5325.1 5342.8 5389.5 5360.9 5389.2 5401.4 5407.5 5407.8 5290.8 5312.2 5323.3 5326.2 5398.0 5401.8 5413.8 5422.1 5439.6 5451.8 5142.9 5238.2 5245.4 5324.0 5336.4 5736.8 5748.1 5756.2 5842.9 5869.1 6269.3 6278.4 6295.2 6301.2 6311.9 6215.9 6218.2 6272.7 6298.1 6315.4 5909.0 5989.4 6028.6 6033.6 6044.0 6356.6 6361.3 6375.9 6380.5 6382.8 6120.1 6152.1 6200.7 6276.2 6291.5 6265.6 6283.0 6290.2 6300.7 6312.0 6243.8 6288.2 6298.4 6311.7 6314.3 6148.4 6179.0 6196.9 6239.1 6328.2 4872.6 5011.6 5012.2 5028.3 5065.9 4536.8 4537.1 4542.5 4585.4 4610.2 5823.9 5871.9 5890.1 5893.4 5947.3 5299.5 6124.0 6217.3 6281.9 6303.9 5835.8 5908.9 5913.1 5922.0 5976.0 5540.6 5547.9 5558.2 5569.6 5573.7 6141.8 6265.9 6296.6 6322.5 6389.9 6252.4 6253.8 6257.7 6261.4 6272.9 6362.7 6382.3 6389.0 6405.9 6422.3 6112.9 6192.8 6212.3 6240.8 6257.1 5472.2 5950.6 6252.9 6260.0 6281.3 6183.1 6204.9 6222.1 6244.5 6277.9 6100.8 6108.4 6158.4 6194.8 6264.4 6112.7 6126.4 6141.8 6149.3 6153.6 6099.6 6214.3 6232.4 6233.2 6235.1 5918.8 6070.1 6112.0 6131.9 6133.3 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:61874.78 Median:19536.17 stddev:132503.63 (678.2%) stddev-sans-first:132795.25 09:33:14 INFO - PROCESS | 1675 | Values: 516.6 522.4 523.2 539.3 560.0 30174.4 30983.5 31699.5 31774.0 31795.0 32502.5 43540.6 44612.6 44641.8 47581.3 8565.9 9746.9 9849.9 9861.1 9885.0 8647.0 9729.9 9845.8 9848.4 9852.0 8693.5 11777.0 11966.9 12044.0 12225.4 12106.3 12143.0 12167.5 12171.0 12212.6 8588.9 10095.7 11468.9 12007.3 12032.9 42437.8 42846.4 42909.0 42920.1 42955.4 3542.3 3605.4 3759.9 3899.8 3938.3 32518.2 33489.2 33498.7 33666.0 33811.5 3262.2 3290.4 3298.8 3392.8 3417.8 547229.0 549761.0 551657.2 552548.2 655427.8 63585.0 64409.4 64472.7 69177.0 69285.0 69162.0 69360.0 69800.7 69813.7 69834.0 17009.2 17145.4 17146.7 17152.0 17153.0 12994.4 13174.0 13177.1 13184.2 13204.3 17357.0 18456.4 19916.6 20142.2 20613.4 8031.4 8056.3 8078.7 8092.9 8100.4 922.2 992.4 1005.1 1067.8 1103.3 30446.0 31376.8 31940.8 31943.3 32359.7 1046.7 1065.2 1066.8 1078.9 1156.0 15924.8 16133.7 16193.5 16380.6 16604.1 179673.0 182309.0 182560.0 183394.0 184042.0 62221.7 62786.1 62927.0 63296.4 63440.7 1378.5 1426.2 1446.1 1454.2 1459.6 30769.7 30906.0 31023.2 31314.7 31937.0 54567.0 54685.2 55062.7 55160.0 55164.0 595609.0 607442.0 610931.0 611042.0 611791.0 24417.1 24856.1 27705.6 28077.6 28193.3 42428.0 50525.0 51341.5 54249.5 54404.0 39761.6 42402.0 42428.4 42428.8 43440.6 32876.8 41773.8 41922.2 42402.2 42613.0 39290.2 39476.8 39773.0 40357.0 40759.2 34973.7 35466.9 36572.0 36584.6 36697.4 22115.9 26254.1 26577.1 26786.6 26835.8 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:35947.11 Median:2432.44 stddev:105300.15 (4329.0%) stddev-sans-first:105561.61 09:33:14 INFO - PROCESS | 1675 | Values: 342.4 381.2 384.6 387.9 407.9 15006.0 16118.3 19370.6 21480.2 21549.6 21381.0 21892.5 22294.3 22424.0 22446.0 5957.9 6164.2 6167.2 6192.6 6193.0 4166.2 6556.5 6858.3 6885.8 6947.5 5690.5 6047.3 6827.5 6829.8 6872.9 5961.9 6037.1 6037.9 6081.8 6115.6 5847.8 5958.6 6003.8 6005.8 6011.0 13362.2 13640.3 13858.0 13867.3 13874.4 2412.3 2452.6 2555.3 2690.5 2732.0 10144.6 10672.0 12429.6 15028.5 16173.5 1800.7 1863.8 1876.2 1945.4 2133.0 121433.0 125460.0 126900.0 127217.0 131556.3 11360.7 11586.6 11687.0 11695.7 11809.6 14433.2 14674.8 14776.6 14786.0 14794.6 6985.0 8834.0 8879.8 8950.3 8950.6 8853.1 8869.2 8890.4 8987.6 9032.5 4410.9 4579.8 4613.7 4659.0 4660.5 1889.1 1930.1 1938.7 1943.4 1962.2 728.1 822.4 847.5 876.7 884.1 21999.8 22510.7 22517.5 22610.3 22753.3 887.3 925.4 940.4 950.0 1005.3 11554.7 11867.2 11942.8 11950.9 11980.2 608763.0 626777.0 635098.0 637768.0 648688.0 81112.6 84171.0 119241.0 148893.0 160232.0 960.3 990.6 1005.9 1007.5 1008.2 15965.8 20095.8 20193.1 20760.9 20836.7 38275.4 51011.0 58409.5 59561.0 60008.7 75659.0 78005.8 80509.0 87965.6 88730.8 7153.0 7457.3 7461.9 7710.1 8222.5 11933.3 12903.8 13152.3 13186.0 13352.5 10782.6 11412.2 11568.3 11581.0 11624.7 11088.9 11331.7 11397.5 11453.9 11554.1 5793.5 8302.5 11640.4 11713.6 11879.9 6811.5 7039.5 7184.2 7474.6 7661.2 10747.3 11439.3 11442.5 11445.1 11530.5 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5774.89 Median:4837.50 stddev:3239.46 (67.0%) stddev-sans-first:3232.93 09:33:14 INFO - PROCESS | 1675 | Values: 1541.2 1572.4 1776.4 1912.5 1918.1 8574.5 8857.9 9036.9 9040.0 9042.9 6240.3 8105.8 8221.8 8514.5 8743.0 2354.0 2375.0 2375.7 2376.9 2381.0 2357.4 2358.4 2408.0 2408.3 2417.9 2097.9 2177.9 2310.7 2386.9 2387.5 2365.1 2365.9 2379.9 2382.2 2389.1 2384.2 2384.3 2385.1 2387.1 2387.3 7697.8 8958.3 9014.3 9029.8 9042.6 5260.8 5371.5 5416.0 5418.3 5429.6 3517.9 3586.9 3701.2 3703.2 3705.4 1049.1 1067.8 1077.1 1093.5 1096.5 11900.2 11912.9 11929.8 11931.8 11946.0 4045.9 4616.8 4718.5 4829.5 4845.5 10036.9 10064.1 10079.4 10081.4 10096.5 5420.4 5423.2 5426.5 5431.1 5447.6 4780.2 4948.4 4959.4 4975.3 4998.3 3814.9 3833.8 3844.0 3881.2 3888.5 1089.4 1157.0 1160.9 1161.3 1170.3 682.7 844.2 948.6 967.5 985.5 5987.3 6026.5 6052.4 6164.2 6205.5 966.4 976.1 984.4 996.5 1014.6 4007.9 4078.2 4082.2 4119.9 4142.6 10533.6 10932.3 10986.7 10996.6 11076.2 9941.8 10078.3 10915.9 10942.2 10956.2 8029.8 8277.5 8357.4 8389.9 8391.2 5954.7 6038.9 6128.3 6178.0 6219.6 9497.0 10071.3 10072.0 10081.1 10191.6 10297.3 10320.3 10342.1 10348.0 10348.2 7984.5 8246.1 8262.8 8314.4 8400.0 8449.7 8468.5 8538.7 8554.7 8560.0 7228.5 7314.9 7416.8 7498.2 7579.3 7387.4 7437.0 7491.3 7501.1 7576.8 5993.9 6950.8 6986.2 7052.0 7058.5 4689.6 4870.8 4918.1 5051.3 5146.4 4632.0 5070.4 5099.5 5125.4 5329.6 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:4559.72 Median:5210.85 stddev:1425.63 (27.4%) stddev-sans-first:1393.27 09:33:14 INFO - PROCESS | 1675 | Values: 297.5 301.5 312.3 315.0 316.0 4669.5 5170.9 5372.7 5379.0 5386.3 4350.4 5189.4 5305.9 5354.6 5391.8 4115.8 4119.2 4125.4 4136.9 4142.4 3390.3 4002.5 4136.6 4169.8 4194.7 3271.8 4040.5 4051.0 4058.7 4068.7 3594.1 3977.6 4042.9 4062.6 4072.5 3512.1 3522.2 3525.4 3535.3 3562.9 4909.3 5205.0 5216.7 5246.2 5259.6 3967.4 4473.7 4599.5 4622.6 4658.7 5620.2 5622.7 5623.4 5627.5 5627.7 3739.0 3832.5 3855.3 3867.6 3879.0 5193.7 5602.5 5917.4 5975.7 5976.1 3947.7 3954.7 3963.2 3966.9 3976.3 3339.8 3519.9 3766.6 3875.7 3938.3 3959.0 4021.8 4592.6 4606.3 4628.8 4599.9 4687.7 4924.0 5571.2 5689.0 1030.5 1141.4 1322.6 1328.5 1339.1 743.9 879.7 887.7 903.9 908.0 4760.4 5138.5 5226.8 5228.3 5240.3 5632.9 5722.6 5738.2 5752.5 5753.0 4502.4 5102.7 5154.0 5274.7 5325.2 2312.9 2592.7 2996.4 3049.7 3057.0 5058.6 5874.8 5968.9 5994.7 6029.3 4403.4 5703.9 6010.0 6014.9 6059.4 5875.8 5906.1 5945.0 5955.2 6039.9 5526.3 5745.6 5818.9 5916.6 5922.8 5225.7 5634.0 5729.3 5734.7 5789.9 5986.4 5995.2 6034.0 6047.8 6054.8 3746.3 3864.8 3895.8 3940.8 3941.3 4490.6 4751.6 5333.9 5469.0 5867.7 4417.3 5397.8 5573.8 5707.0 5713.0 4894.7 5672.8 5679.5 5681.9 5686.2 5287.3 5519.2 5526.3 5564.3 5567.6 5576.9 5609.2 5659.8 5669.2 5669.7 5248.9 5327.9 5412.9 5547.7 5594.7 09:33:14 INFO - PROCESS | 1675 | 09:33:14 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:26507.04 Median:18059.46 stddev:43150.25 (238.9%) stddev-sans-first:43227.93 09:33:14 INFO - PROCESS | 1675 | Values: 740.7 761.8 772.1 847.9 849.3 17366.9 18037.5 18046.5 18048.0 18070.9 17173.1 17318.5 17378.0 17391.7 17415.3 696.9 874.3 884.7 885.5 887.6 717.9 866.7 896.7 903.1 905.0 910.3 920.3 922.1 923.1 923.8 725.6 908.1 916.9 917.1 917.9 680.2 684.2 685.9 686.6 691.1 15698.7 15778.8 15786.8 15802.2 15869.6 13815.3 14021.2 14095.0 14259.1 14262.6 13318.9 13395.9 13447.3 13532.4 13808.4 4625.8 4655.1 4704.7 4795.1 4834.8 122907.0 123023.0 124641.4 124897.0 127316.7 11092.2 11093.2 11137.9 11219.4 11545.8 22258.3 22276.6 22278.7 22294.9 22332.2 6546.8 8016.4 8030.3 8055.6 8169.4 4456.3 4527.6 4547.7 4626.2 4753.6 3454.8 3552.3 3556.7 3557.8 3562.0 629.9 646.6 648.2 658.0 662.8 594.3 602.8 613.8 634.8 648.2 8170.6 8269.9 8453.9 8794.5 9027.3 532.3 613.7 624.8 626.5 627.1 4135.9 4452.8 4927.5 5010.6 5122.2 135207.6 164879.2 165198.2 170954.1 171663.1 134399.0 136068.6 137215.0 139621.0 142199.3 125207.0 127696.4 129438.0 129611.0 132585.0 10079.1 10088.5 10090.6 10091.0 10092.4 46769.8 53871.7 60181.5 62352.0 68289.0 62006.0 62137.7 90635.0 103802.9 104773.5 12542.0 13640.9 13655.7 13795.8 14084.2 15409.7 15436.6 15625.0 15856.4 15901.4 14342.9 14494.8 14559.5 14597.0 14638.6 13971.8 14170.5 14540.8 14837.4 14974.0 14015.2 14061.8 14286.2 14786.8 14856.9 10907.0 11274.7 11359.9 11386.4 11932.7 10180.0 11455.7 11632.8 11855.7 11881.8 09:33:14 INFO - PROCESS | 1675 | -------- Summary: end -------- 09:33:14 INFO - PROCESS | 1675 | 09:33:15 INFO - TEST-INFO | 1675: exit 0 09:33:15 INFO - TEST-OK | dromaeo_css | took 1117452ms 09:33:15 INFO - TEST-START | kraken 09:33:15 INFO - operating with platform_type : mac_ 09:33:15 INFO - Initialising browser for kraken test... 09:33:15 INFO - TEST-INFO | started process 1794 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmprWvGo2/profile http://localhost:49277/getInfo.html) 09:33:17 INFO - PROCESS | 1794 | __metrics Screen width/height:1600/1200 09:33:17 INFO - PROCESS | 1794 | colorDepth:24 09:33:17 INFO - PROCESS | 1794 | Browser inner width/height: 1024/689 09:33:17 INFO - PROCESS | 1794 | __metrics 09:33:17 INFO - PROCESS | 1794 | 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" 09:33:17 INFO - PROCESS | 1794 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:33:17 INFO - PROCESS | 1794 | ************************* 09:33:17 INFO - PROCESS | 1794 | A coding exception was thrown in a Promise resolution callback. 09:33:17 INFO - PROCESS | 1794 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 09:33:17 INFO - PROCESS | 1794 | 09:33:17 ERROR - PROCESS | 1794 | Full message: TypeError: win is null 09:33:17 INFO - PROCESS | 1794 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:858:17 09:33:17 INFO - PROCESS | 1794 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 09:33:17 INFO - PROCESS | 1794 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 09:33:17 INFO - PROCESS | 1794 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 09:33:17 INFO - PROCESS | 1794 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 09:33:17 INFO - PROCESS | 1794 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 09:33:17 INFO - PROCESS | 1794 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 09:33:17 INFO - PROCESS | 1794 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 09:33:17 INFO - PROCESS | 1794 | postMessage@resource://gre/modules/PromiseWorker.jsm:292:9 09:33:17 INFO - PROCESS | 1794 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 09:33:17 INFO - PROCESS | 1794 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 09:33:17 INFO - PROCESS | 1794 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 09:33:17 INFO - PROCESS | 1794 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 09:33:17 INFO - PROCESS | 1794 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 09:33:17 INFO - PROCESS | 1794 | Promise.prototype.then@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:454:5 09:33:17 INFO - PROCESS | 1794 | this.DeferredSave.prototype._deferredSave@resource://gre/modules/DeferredSave.jsm:220:5 09:33:17 INFO - PROCESS | 1794 | this.DeferredSave.prototype._startTimer/<@resource://gre/modules/DeferredSave.jsm:175:40 09:33:17 INFO - PROCESS | 1794 | syncLoadManifestFromFile@resource://gre/modules/addons/XPIProvider.jsm:1515:5 09:33:17 INFO - PROCESS | 1794 | addMetadata@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:1647:21 09:33:17 INFO - PROCESS | 1794 | processFileChanges@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2011:23 09:33:17 INFO - PROCESS | 1794 | this.XPIProvider.checkForChanges@resource://gre/modules/addons/XPIProvider.jsm:3726:34 09:33:17 INFO - PROCESS | 1794 | this.XPIProvider.startup@resource://gre/modules/addons/XPIProvider.jsm:2715:25 09:33:17 INFO - PROCESS | 1794 | callProvider@resource://gre/modules/AddonManager.jsm:227:12 09:33:17 INFO - PROCESS | 1794 | _startProvider@resource://gre/modules/AddonManager.jsm:779:5 09:33:17 INFO - PROCESS | 1794 | AddonManagerInternal.startup@resource://gre/modules/AddonManager.jsm:963:9 09:33:17 INFO - PROCESS | 1794 | this.AddonManagerPrivate.startup@resource://gre/modules/AddonManager.jsm:2985:5 09:33:17 INFO - PROCESS | 1794 | amManager.prototype.observe@resource://gre/components/addonManager.js:71:9 09:33:17 INFO - PROCESS | 1794 | 09:33:17 INFO - PROCESS | 1794 | ************************* 09:33:18 INFO - TEST-INFO | 1794: exit 0 09:33:18 INFO - Browser initialized. 09:33:18 INFO - Running cycle 1/1 for kraken test... 09:33:18 INFO - TEST-INFO | started process 1795 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmprWvGo2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:33:30 INFO - PROCESS | 1795 | RSS: Main: 264675328 09:33:30 INFO - PROCESS | 1795 | 09:33:32 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 09:33:33 INFO - PROCESS | 1795 | RSS: Main: 311918592 09:33:33 INFO - PROCESS | 1795 | 09:33:36 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 09:33:37 INFO - PROCESS | 1795 | RSS: Main: 322502656 09:33:37 INFO - PROCESS | 1795 | 09:33:40 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 09:33:41 INFO - PROCESS | 1795 | RSS: Main: 404570112 09:33:41 INFO - PROCESS | 1795 | 09:33:43 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 09:33:43 INFO - PROCESS | 1795 | RSS: Main: 308219904 09:33:43 INFO - PROCESS | 1795 | 09:33:45 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:46 INFO - PROCESS | 1795 | RSS: Main: 311685120 09:33:46 INFO - PROCESS | 1795 | 09:33:49 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:50 INFO - PROCESS | 1795 | RSS: Main: 347807744 09:33:50 INFO - PROCESS | 1795 | 09:33:52 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:53 INFO - PROCESS | 1795 | RSS: Main: 349556736 09:33:53 INFO - PROCESS | 1795 | 09:33:56 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 09:33:56 INFO - PROCESS | 1795 | RSS: Main: 347406336 09:33:56 INFO - PROCESS | 1795 | 09:33:58 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:59 INFO - PROCESS | 1795 | RSS: Main: 329273344 09:33:59 INFO - PROCESS | 1795 | 09:34:01 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:34:01 INFO - PROCESS | 1795 | RSS: Main: 336617472 09:34:01 INFO - PROCESS | 1795 | 09:34:03 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:34:04 INFO - PROCESS | 1795 | RSS: Main: 335556608 09:34:04 INFO - PROCESS | 1795 | 09:34:07 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:34:07 INFO - PROCESS | 1795 | RSS: Main: 342351872 09:34:07 INFO - PROCESS | 1795 | 09:34:10 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:34:11 INFO - PROCESS | 1795 | RSS: Main: 343900160 09:34:11 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 09:34:13 INFO - PROCESS | 1795 | RSS: Main: 387338240 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | __start_tp_report 09:34:13 INFO - PROCESS | 1795 | _x_x_mozilla_page_load 09:34:13 INFO - PROCESS | 1795 | _x_x_mozilla_page_load_details 09:34:13 INFO - PROCESS | 1795 | |i|pagename|runs| 09:34:13 INFO - PROCESS | 1795 | |0;ai-astar;101;113;114;113;113;114;113;113;114;114 09:34:13 INFO - PROCESS | 1795 | |1;audio-beat-detection;143;107;106;178;138;109;129;105;143;107 09:34:13 INFO - PROCESS | 1795 | |2;audio-dft;185;183;185;184;183;185;178;181;185;183 09:34:13 INFO - PROCESS | 1795 | |3;audio-fft;77;72;58;108;74;58;66;61;99;57 09:34:13 INFO - PROCESS | 1795 | |4;audio-oscillator;98;97;91;72;93;97;128;74;68;101 09:34:13 INFO - PROCESS | 1795 | |5;imaging-gaussian-blur;89;86;90;87;88;86;89;86;90;85 09:34:13 INFO - PROCESS | 1795 | |6;imaging-darkroom;94;94;96;94;114;94;96;95;95;94 09:34:13 INFO - PROCESS | 1795 | |7;imaging-desaturate;85;87;87;85;84;85;84;86;83;84 09:34:13 INFO - PROCESS | 1795 | |8;json-parse-financial;56;55;56;56;56;56;56;52;54;54 09:34:13 INFO - PROCESS | 1795 | |9;json-stringify-tinderbox;65;71;85;77;73;72;71;73;72;74 09:34:13 INFO - PROCESS | 1795 | |10;stanford-crypto-aes;83;86;86;83;82;84;84;83;86;88 09:34:13 INFO - PROCESS | 1795 | |11;stanford-crypto-ccm;133;136;138;140;141;139;118;137;175;134 09:34:13 INFO - PROCESS | 1795 | |12;stanford-crypto-pbkdf2;165;164;163;164;163;162;145;202;223;181 09:34:13 INFO - PROCESS | 1795 | |13;stanford-crypto-sha256-iterative;71;77;84;83;83;70;65;65;64;65 09:34:13 INFO - PROCESS | 1795 | __end_tp_report 09:34:13 INFO - PROCESS | 1795 | __start_cc_report 09:34:13 INFO - PROCESS | 1795 | _x_x_mozilla_cycle_collect,2050 09:34:13 INFO - PROCESS | 1795 | __end_cc_report 09:34:13 INFO - PROCESS | 1795 | __startTimestamp1468254853530__endTimestamp 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | ------- Summary: start ------- 09:34:13 INFO - PROCESS | 1795 | Number of tests: 14 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.50 stddev:3.97 (3.5%) stddev-sans-first:0.53 09:34:13 INFO - PROCESS | 1795 | Values: 101.0 113.0 114.0 113.0 113.0 114.0 113.0 113.0 114.0 114.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:133.50 stddev:24.23 (18.2%) stddev-sans-first:24.95 09:34:13 INFO - PROCESS | 1795 | Values: 143.0 107.0 106.0 178.0 138.0 109.0 129.0 105.0 143.0 107.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#2] audio-dft Cycles:10 Average:183.20 Median:184.50 stddev:2.25 (1.2%) stddev-sans-first:2.29 09:34:13 INFO - PROCESS | 1795 | Values: 185.0 183.0 185.0 184.0 183.0 185.0 178.0 181.0 185.0 183.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#3] audio-fft Cycles:10 Average:73.00 Median:69.00 stddev:17.69 (25.6%) stddev-sans-first:18.71 09:34:13 INFO - PROCESS | 1795 | Values: 77.0 72.0 58.0 108.0 74.0 58.0 66.0 61.0 99.0 57.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#4] audio-oscillator Cycles:10 Average:91.90 Median:92.00 stddev:17.53 (19.1%) stddev-sans-first:18.45 09:34:13 INFO - PROCESS | 1795 | Values: 98.0 97.0 91.0 72.0 93.0 97.0 128.0 74.0 68.0 101.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.50 stddev:1.84 (2.1%) stddev-sans-first:1.88 09:34:13 INFO - PROCESS | 1795 | Values: 89.0 86.0 90.0 87.0 88.0 86.0 89.0 86.0 90.0 85.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#6] imaging-darkroom Cycles:10 Average:96.60 Median:94.50 stddev:6.17 (6.5%) stddev-sans-first:6.47 09:34:13 INFO - PROCESS | 1795 | Values: 94.0 94.0 96.0 94.0 114.0 94.0 96.0 95.0 95.0 94.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:1.33 (1.6%) stddev-sans-first:1.41 09:34:13 INFO - PROCESS | 1795 | Values: 85.0 87.0 87.0 85.0 84.0 85.0 84.0 86.0 83.0 84.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.37 (2.4%) stddev-sans-first:1.41 09:34:13 INFO - PROCESS | 1795 | Values: 56.0 55.0 56.0 56.0 56.0 56.0 56.0 52.0 54.0 54.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.00 stddev:5.10 (7.0%) stddev-sans-first:4.44 09:34:13 INFO - PROCESS | 1795 | Values: 65.0 71.0 85.0 77.0 73.0 72.0 71.0 73.0 72.0 74.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:85.00 stddev:1.90 (2.2%) stddev-sans-first:1.94 09:34:13 INFO - PROCESS | 1795 | Values: 83.0 86.0 86.0 83.0 82.0 84.0 84.0 83.0 86.0 88.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:138.50 stddev:14.21 (10.3%) stddev-sans-first:14.90 09:34:13 INFO - PROCESS | 1795 | Values: 133.0 136.0 138.0 140.0 141.0 139.0 118.0 137.0 175.0 134.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:164.50 stddev:22.94 (13.9%) stddev-sans-first:24.14 09:34:13 INFO - PROCESS | 1795 | Values: 165.0 164.0 163.0 164.0 163.0 162.0 145.0 202.0 223.0 181.0 09:34:13 INFO - PROCESS | 1795 | 09:34:13 INFO - PROCESS | 1795 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.70 Median:74.00 stddev:8.31 (11.2%) stddev-sans-first:8.80 09:34:13 INFO - PROCESS | 1795 | Values: 71.0 77.0 84.0 83.0 83.0 70.0 65.0 65.0 64.0 65.0 09:34:13 INFO - PROCESS | 1795 | -------- Summary: end -------- 09:34:13 INFO - PROCESS | 1795 | 09:34:14 INFO - TEST-INFO | 1795: exit 0 09:34:14 INFO - TEST-OK | kraken | took 59069ms 09:34:14 INFO - SUITE-END | took 1176s 09:34:14 INFO - Completed test suite (00:19:36) 09:34:14 INFO - javascript benchmark 09:34:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3364.3103163851415, 3373.6289008209096, 3403.2002479417347, 3405.1316914186887, 3456.239627281997, 5685.914711279334, 5778.76884924603, 5794.246747922774, 5840.474357307841, 5895.793647222348, 5781.682007489587, 5822.155787410823, 5830.941690583093, 5832.125181222818, 5845.620034697742, 5257.369115706101, 5292.994331077102, 5325.147976323789, 5342.839714808542, 5389.51494365507, 5360.865978350534, 5389.164679474668, 5401.4328495508, 5407.459254074581, 5407.810726624569, 5290.788368465257, 5312.17661262503, 5323.307969963899, 5326.174442961347, 5398.0, 5401.837944861641, 5413.783448662049, 5422.0511410503395, 5439.564834813205, 5451.781928722847, 5142.871428214325, 5238.214267859814, 5245.449227831061, 5324.0150572144275, 5336.359636843604, 5736.827895163152, 5748.137779333092, 5756.1653560233535, 5842.941570584282, 5869.1196320551835, 6269.310375858652, 6278.40355166263, 6295.181626388586, 6301.243850737895, 6311.93688063118, 6215.906761398583, 6218.191635087455, 6272.686365681698, 6298.055291706235, 6315.431611155018, 5909.0, 5989.401059894063, 6028.577999540078, 6033.637981721111, 6044.032954727222, 6356.586821856565, 6361.300256971729, 6375.904361434582, 6380.521460890452, 6382.808515744535, 6120.14317995481, 6152.107944348045, 6200.658963757037, 6276.152719382825, 6291.5281353898645, 6265.624062556261, 6283.0, 6290.24517057956, 6300.653464059521, 6311.96844015777, 6243.750249989949, 6288.213973253343, 6298.370162983757, 6311.747530098744, 6314.273858506239, 6148.385161483816, 6178.969105154445, 6196.8760624787255, 6239.064140378889, 6328.240611126695, 4872.561469467765, 5011.599072074226, 5012.172991456368, 5028.346314979102, 5065.924011139836, 4536.818527258939, 4537.137943790669, 4542.454905411372, 4585.426821647294, 4610.170169369479, 5823.941760582425, 5871.853203669857, 5890.087036509348, 5893.351731309553, 5947.286325640951, 5299.470052994747, 6124.045297608036, 6217.253929528395, 6281.937180628227, 6303.936960630427, 5835.766569337179, 5908.881822363529, 5913.053911374245, 5922.0, 5975.970120149372, 5540.63985840919, 5547.916781248285, 5558.249636299129, 5569.637973531708, 5573.749181286852, 6141.792871882774, 6265.937340626718, 6296.590721603081, 6322.46259067966, 6389.8722025560155, 6252.437280644764, 6253.812385628438, 6257.7184026719815, 6261.373862613702, 6272.937270627235, 6362.713677884413, 6382.32985536512, 6389.041643753289, 6405.903911441425, 6422.293547709842, 6112.929053818819, 6192.81421557354, 6212.347703491249, 6240.812775616739, 6257.1240026395435, 5472.179173124063, 5950.613210141327, 6252.937470625236, 6259.96870015647, 6281.309056003927, 6183.103449999815, 6204.875902481835, 6222.128901953821, 6244.500439964701, 6277.937220627735, 6100.816975490742, 6108.402780883668, 6158.3841615838055, 6194.783182588588, 6264.373562643699, 6112.663803490851, 6126.376510224705, 6141.785037523665, 6149.2620885494025, 6153.569250152446, 6099.634021958696, 6214.25428948529, 6232.439080482778, 6233.158523599437, 6235.064740288993, 5918.822435326947, 6070.119832624333, 6112.0, 6131.938680613315, 6133.264008318852], "value": 5791.842210097366, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.5764073459762, 522.4409881426875, 523.2125650895405, 539.2585195356385, 559.9776008959643, 30174.396512069747, 30983.535246971285, 31699.52450713241, 31774.0, 31794.99999999997, 32502.537385817635, 43540.564594354044, 44612.55387446124, 44641.77679111608, 47581.28628070582, 8565.871511927326, 9746.853797193031, 9849.901500985006, 9861.11249987501, 9884.950575247114, 8647.0, 9729.902700973005, 9845.753856153618, 9848.40909545426, 9851.950740246324, 8693.47839129651, 11777.0, 11966.880331196708, 12044.036477081856, 12225.38873056348, 12106.334151621657, 12142.967847732865, 12167.51329946801, 12170.965467935148, 12212.572559960403, 8588.914110858874, 10095.697129086138, 11468.885311146949, 12007.3395963222, 12032.939835300855, 42437.78781106075, 42846.357304640456, 42909.0, 42920.14159716788, 42955.35566966467, 3542.3269578780205, 3605.405108157177, 3759.9436008459893, 3899.78551179685, 3938.330483817729, 32518.18704532382, 33489.16277093068, 33498.6650133498, 33665.96008478974, 33811.4928276076, 3262.151840521458, 3290.3748287825238, 3298.752593555493, 3392.8133952632597, 3417.7607567470286, 547229.0, 549761.0, 551657.2417137888, 552548.2372588111, 655427.7828886886, 63585.0, 64409.35590644034, 64472.677636611515, 69177.0, 69285.0, 69161.96257056149, 69359.95960060603, 69800.65099674469, 69813.65093174501, 69834.0, 17009.23458444373, 17145.399911003056, 17146.657066858592, 17152.0, 17153.0, 12994.415251313714, 13174.0, 13177.143485673401, 13184.208947463183, 13204.339783010812, 17357.0, 18456.405882602387, 19916.60166796656, 20142.194312227348, 20613.381598552067, 8031.397645176518, 8056.274935255855, 8078.676852925817, 8092.878606820902, 8100.392470564731, 922.2437601166979, 992.4343124419012, 1005.1456262177092, 1067.7917806027906, 1103.3049179017169, 30446.0, 31376.8228242342, 31940.840295798836, 31943.337583678785, 32359.67640323614, 1046.6546039806844, 1065.2383545764815, 1066.8239740442896, 1078.8671894510733, 1155.9537618495167, 15924.778628843473, 16133.67732645364, 16193.514194574182, 16380.590485237964, 16604.086775227235, 179673.0, 182309.0, 182560.0, 183394.0, 184042.0, 62221.688891555255, 62786.058209125986, 62927.0, 63296.36703632997, 63440.68279658665, 1378.4899587152818, 1426.179946530749, 1446.11064195519, 1454.1856560326462, 1459.5621313605664, 30769.69230307758, 30906.0, 31023.224419388793, 31314.691256215563, 31937.041888743373, 54567.0, 54685.179722304994, 55062.724686376314, 55160.0, 55164.0, 595609.0, 607442.0, 610931.0, 611042.0, 611791.0, 24417.145399910918, 24856.130035448674, 27705.584416234175, 28077.57883631706, 28193.295167620974, 42428.0295016312, 50525.0, 51341.48658513367, 54249.45750542602, 54404.0, 39761.60238397579, 42402.0, 42428.363574547024, 42428.787856060524, 43440.56559434365, 32876.835615822725, 41773.79113104415, 41922.161556768086, 42402.15195696131, 42613.0, 39290.2141957165, 39476.80261598789, 39773.00567485836, 40357.0, 40759.1848163041, 34973.65026349704, 35466.93599192028, 36572.0, 36584.634153658124, 36697.44953825641, 22115.889420552794, 26254.081107161157, 26577.069802556816, 26786.59820102661, 26835.792389342918], "value": 20367.253470881944, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.40250762419583, 381.2394273424518, 384.552161113408, 387.90302424393883, 407.90210349516127, 15006.0, 16118.274677639505, 19370.612587748255, 21480.248191313287, 21549.56900861982, 21381.0378532966, 21892.45268868279, 22294.331170064885, 22424.0, 22446.0, 5957.910631340534, 6164.198654174951, 6167.167432396636, 6192.628442293454, 6193.0, 4166.187593419285, 6556.508261880354, 6858.314168583127, 6885.758998435056, 6947.513674042819, 5690.516306113986, 6047.304559975615, 6827.52207345486, 6829.829254268659, 6872.896906546406, 5961.940380596182, 6037.064255040454, 6037.909431358533, 6081.787137450212, 6115.5719099663265, 5847.8245652630485, 5958.5828991970575, 6003.759849606011, 6005.759769609211, 6010.9699451502465, 13362.198268103944, 13640.317984100855, 13857.960652950967, 13867.306634668226, 13874.4450221991, 2412.300432874464, 2452.583060879637, 2555.348386161517, 2690.5426077566863, 2732.0, 10144.644937427229, 10672.0, 12429.627111186679, 15028.474003409938, 16173.458226438419, 1800.7208882623215, 1863.8322550970342, 1876.2307453943954, 1945.4260993007097, 2132.9786702132933, 121433.0, 125460.0, 126900.0, 127217.0, 131556.3422182883, 11360.659180224606, 11586.594469193538, 11687.006604438548, 11695.707607309716, 11809.645710628694, 14433.20617366055, 14674.779878301833, 14776.630584235265, 14785.964982451125, 14794.630134246514, 6984.965075174693, 8834.0, 8879.822403551892, 8950.328725345626, 8950.597223124974, 8853.096584234292, 8869.157430044135, 8890.422122562013, 8987.640494380283, 9032.548372581345, 4410.911781764346, 4579.793909274091, 4613.685099746556, 4659.0, 4660.487346391894, 1889.1121173048646, 1930.0952067138867, 1938.7479627648597, 1943.3975467605087, 1962.1646720488757, 728.1153398620595, 822.3914303415429, 847.4745657692271, 876.6581033396981, 884.1291328041874, 21999.790011549198, 22510.66234006491, 22517.549649007255, 22610.321690349316, 22753.317400478012, 887.3300658003295, 925.4354843545432, 940.4404379394249, 950.011987532958, 1005.3113617172261, 11554.653360399201, 11867.188864220287, 11942.761144777056, 11950.924416802529, 11980.161388702882, 608763.0, 626777.0, 635098.0, 637768.0, 648688.0, 81112.59443702862, 84171.0, 119241.0, 148893.0, 160232.0, 960.3229723045237, 990.5740531571349, 1005.8843233028296, 1007.536533194724, 1008.2035192198229, 15965.840341596435, 20095.799042009392, 20193.091310891337, 20760.89619551953, 20836.68744968857, 38275.42586861143, 51010.97978040492, 58409.5397615046, 59561.0, 60008.69995650168, 75659.0, 78005.82991255022, 80509.0, 87965.56017219873, 88730.79595846302, 7152.964235178791, 7457.2542745724995, 7461.925380746123, 7710.11333696632, 8222.465539739898, 11933.343666098448, 12903.838654521138, 13152.276624785729, 13186.011049171542, 13352.465901363837, 10782.568697252022, 11412.225533484194, 11568.305901645928, 11581.0, 11624.709382265512, 11088.889111108785, 11331.716707082389, 11397.487113080097, 11453.942730286295, 11554.075673946232, 5793.478586927196, 8302.54336011525, 11640.417979101181, 11713.590024349107, 11879.940600297288, 6811.523193376416, 7039.54242974205, 7184.1738200105565, 7474.626268686653, 7661.195574464601, 10747.301425407324, 11439.256448330832, 11442.485088170886, 11445.141614378788, 11530.481128349413], "value": 9620.47258088363, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.2062787664352, 1572.3946280681937, 1776.4315419065904, 1912.4836294200556, 1918.0697361779535, 8574.528400937947, 8857.91142088579, 9036.909630903689, 9039.954800225998, 9042.909570904289, 6240.344763799803, 8105.756827295176, 8221.807837863522, 8514.48913065215, 8743.038265790774, 2354.0348457132536, 2375.0381095656285, 2375.726791418983, 2376.869272190029, 2381.047580967618, 2357.351728274721, 2358.445765245166, 2407.9759202408013, 2408.2534414331612, 2417.867017314047, 2097.863638863477, 2177.858439201455, 2310.688057112285, 2386.940326491843, 2387.546366190427, 2365.089440565386, 2365.8580485170946, 2379.9171377023413, 2382.213869423086, 2389.0682633772844, 2384.225126833786, 2384.332386931662, 2385.1413491143176, 2387.1286980252257, 2387.3196139100446, 7697.769066928001, 8958.328125390623, 9014.323925705598, 9029.819403611957, 9042.593083311202, 5260.842174734764, 5371.5165635092635, 5416.0, 5418.295621569171, 5429.592780541475, 3517.8944631661093, 3586.8923932282073, 3701.222743223926, 3703.203811180609, 3705.3886108792017, 1049.1134990932649, 1067.813132701774, 1077.138289368502, 1093.5352475198008, 1096.4517741129434, 11900.226485278428, 11912.880871191264, 11929.821052684216, 11931.761364772656, 11946.0, 4045.85569648087, 4616.76916154191, 4718.528147185254, 4829.468758436569, 4845.53967373099, 10036.946120657372, 10064.094231519199, 10079.445630490247, 10081.445520496443, 10096.495175241356, 5420.376656684453, 5423.159410291411, 5426.511613954763, 5431.131019036935, 5447.618666693373, 4780.1873681474235, 4948.356713627206, 4959.404871415453, 4975.328330675385, 4998.275250088716, 3814.923701525954, 3833.769973801581, 3844.0, 3881.1849511602313, 3888.4944957155953, 1089.4008295437477, 1156.9942150289362, 1160.9419529023685, 1161.3380373187201, 1170.3329102411544, 682.66208226927, 844.1727107434748, 948.6158105967095, 967.5452537307455, 985.4678473624306, 5987.281526216795, 6026.487748541335, 6052.394760524, 6164.229471316085, 6205.47253483459, 966.40566051879, 976.1409959235828, 984.3806937587628, 996.5415908681942, 1014.5941623350719, 4007.919841603152, 4078.2455245779624, 4082.2039702258367, 4119.938200926988, 4142.647874930664, 10533.631322903815, 10932.289401188897, 10986.725331866768, 10996.615118470816, 11076.169287303323, 9941.751456213653, 10078.34490758113, 10915.945420273007, 10942.17933654979, 10956.178286628534, 8029.759107226792, 8277.503349799032, 8357.414980951275, 8389.874151887727, 8391.244787969112, 5954.672493012926, 6038.8792224154395, 6128.32588415265, 6177.980633195472, 6219.596768962892, 9497.0, 10071.34536255141, 10072.043155900177, 10081.092701656886, 10191.592336306465, 10297.279190456597, 10320.277580569287, 10342.120919721607, 10348.0, 10348.172146228133, 7984.481008734414, 8246.05170405411, 8262.83474330498, 8314.41799074059, 8399.95800020996, 8449.704260350858, 8468.49189048659, 8538.70114545988, 8554.743357699279, 8560.0, 7228.545520313611, 7314.926850731637, 7416.814579635552, 7498.175200728025, 7579.317861392501, 7387.4459415545125, 7437.0331856858265, 7491.250874912465, 7501.1373692023935, 7576.810579735551, 5993.940060599338, 6950.826229344307, 6986.196587392312, 7052.0, 7058.470614703815, 4689.6248300136585, 4870.829520966749, 4918.114739346952, 5051.3433253676785, 5146.408163061295, 4631.976840115913, 5070.366204224472, 5099.534095722211, 5125.359330083739, 5329.626926115135], "value": 4634.6549768886, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.46307914214833, 301.5100461749657, 312.3238189320122, 315.0485533688263, 316.0439669998255, 4669.463011753649, 5170.9482905170935, 5372.7045012524295, 5378.973105134473, 5386.272853164822, 4350.41269428627, 5189.351331083614, 5305.912287980965, 5354.5716342692585, 5391.81128660497, 4115.835366585333, 4119.196756632457, 4125.443065186191, 4136.8965775855695, 4142.357934520154, 3390.2541440883087, 4002.459667944819, 4136.565660605623, 4169.770662613555, 4194.706370554062, 3271.8364081795935, 4040.5353384360733, 4051.027753339207, 4058.6550143237714, 4068.7151899367054, 3594.0655429588364, 3977.622125898035, 4042.9191416171807, 4062.6343629073526, 4072.4705788247634, 3512.139525816166, 3522.20750331176, 3525.360707271131, 3535.257595904863, 3562.8931132066077, 4909.3126962225315, 5204.992156039866, 5216.713080780555, 5246.239295302198, 5259.552938000236, 3967.444557761916, 4473.724988378297, 4599.5400459953735, 4622.583967442913, 4658.673892827504, 5620.185073164409, 5622.690752008638, 5623.353314368859, 5627.465390787868, 5627.662340259557, 3739.0461535387285, 3832.4826148470156, 3855.265130691172, 3867.59390264021, 3879.0, 5193.68837869729, 5602.495775380195, 5917.437843404869, 5975.671338076404, 5976.133460648225, 3947.684185265172, 3954.694950666273, 3963.207358528306, 3966.8809935701975, 3976.304146774303, 3339.814365900119, 3519.8768043118366, 3766.6233376662663, 3875.689944804465, 3938.3107956107565, 3958.960410395917, 4021.8190181441905, 4592.632589392907, 4606.262997920317, 4628.791704373311, 4599.8730311074005, 4687.718736875799, 4924.0, 5571.192177134293, 5689.0, 1030.5311083457009, 1141.4007645985923, 1322.5833862333334, 1328.4951718346965, 1339.1429485129524, 743.8772602520523, 879.7228872905015, 887.7203680840514, 903.8960519540337, 908.0238743350924, 4760.381150450488, 5138.511841375063, 5226.817061402832, 5228.320318358589, 5240.34495688039, 5632.915506267409, 5722.570807189478, 5738.167965644958, 5752.482276595127, 5752.9712351437975, 4502.369668246448, 5102.668326558762, 5153.974230128825, 5274.709890955957, 5325.227841962894, 2312.8727919964226, 2592.74072592743, 2996.4230761545055, 3049.6645369009393, 3056.9583264651815, 5058.570021548136, 5874.794382196603, 5968.8810472282585, 5994.741104368131, 6029.336772954972, 4403.4495688039, 5703.944770217523, 6010.0, 6014.939850601526, 6059.394060593993, 5875.794347197828, 5906.143609176731, 5944.970275148597, 5955.225820643375, 6039.8792024159275, 5526.281583394133, 5745.626534275259, 5818.912716309174, 5916.615419997686, 5922.822315330547, 5225.733916302311, 5633.971830140823, 5729.312482502127, 5734.7132643368495, 5789.91315130265, 5986.371430999682, 5995.2206213192, 6034.0, 6047.818565443044, 6054.788082417094, 3746.2507498499863, 3864.787436691009, 3895.7857317847793, 3940.842366305316, 3941.349677303213, 4490.618297444623, 4751.598278507918, 5333.866653333697, 5469.0, 5867.6772777497645, 4417.2711502603, 5397.7840886365675, 5573.7770489179975, 5707.0, 5713.0, 4894.730789806595, 5672.801451949162, 5679.460451257124, 5681.857953551194, 5686.232358631697, 5287.312649355559, 5519.172124181405, 5526.309211348582, 5564.33228012641, 5567.582431317586, 5576.888462230814, 5609.24275222856, 5659.764393064799, 5669.20631111653, 5669.744861481161, 5248.947510524846, 5327.866803329948, 5412.860540886355, 5547.694876781816, 5594.664320140804], "value": 4099.815622983036, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.7481456304857, 761.8247803005308, 772.1082150116617, 847.9194476524732, 849.2526972186478, 17366.913165434173, 18037.549061273457, 18046.5488362791, 18048.0, 18070.915745055274, 17173.141342932864, 17318.48044558662, 17377.957322560622, 17391.652166956654, 17415.30338786447, 696.9128858892639, 874.3092956564323, 884.6859364925456, 885.4554449013874, 887.5562218890555, 717.8600172966273, 866.7313132928781, 896.6592694775976, 903.0563061600643, 904.9502277374742, 910.3309067835136, 920.3143657974819, 922.1470140120396, 923.1138107416888, 923.8198551282503, 725.6263024542365, 908.0737647599477, 916.8945571259324, 917.0645941140067, 917.9495127767971, 680.1769858471206, 684.165318311659, 685.8971154326841, 686.5914780705472, 691.0808624529363, 15698.68602627941, 15778.76331855023, 15786.842131578653, 15802.209889505593, 15869.603259918478, 13815.309234538232, 14021.158730476101, 14095.0, 14259.073160244654, 14262.57212283633, 13318.86681133186, 13395.86604133956, 13447.260400677958, 13532.391042403115, 13808.447662093504, 4625.814967401334, 4655.138799322136, 4704.694194877322, 4795.088933102699, 4834.758262086882, 122907.0, 123023.0, 124641.37679311636, 124897.0, 127316.72683273141, 11092.223544351818, 11093.223474356879, 11137.852520572016, 11219.382933938712, 11545.769084618429, 22258.3322500322, 22276.55446891053, 22278.665820012713, 22294.885255737147, 22332.21837235689, 6546.803595892131, 8016.358691304682, 8030.317423018992, 8055.637496312563, 8169.42814003026, 4456.286994080996, 4527.648221245522, 4547.749873756975, 4626.236670949255, 4753.572178503951, 3454.775439596418, 3552.3250582389264, 3556.715462762973, 3557.768745031565, 3561.982190089033, 629.9433051025429, 646.5894157210112, 648.2350826025336, 658.0392626764881, 662.8144119646506, 594.3076316091791, 602.7679343452714, 613.7667686279275, 634.7968650032038, 648.2253706820399, 8170.591470426455, 8269.917300826915, 8453.873191902127, 8794.516301603346, 9027.322950778587, 532.3106576982816, 613.7452957022875, 624.7938180400457, 626.5363630913182, 627.075064280183, 4135.937960930589, 4452.821887124479, 4927.531884470969, 5010.624203184776, 5122.206305855319, 135207.64792351951, 164879.1756041212, 165198.17400912917, 170954.14522927307, 171663.14168429078, 134399.0, 136068.63931360558, 137215.0, 139621.0, 142199.28900355432, 125207.0, 127696.36151819181, 129438.0, 129611.0, 132585.0, 10079.143272821599, 10088.495575221357, 10090.646827361006, 10091.0, 10092.444915529717, 46769.830754231414, 53871.73064134654, 60181.495462613784, 62352.0, 68289.0, 62006.0, 62137.68931155315, 90635.0, 103802.92394151924, 104773.47613261885, 12542.0, 13640.92177245601, 13655.658608534468, 13795.79306310386, 14084.15495070299, 15409.69180616359, 15436.614084647974, 15625.0, 15856.437240934158, 15901.443449479213, 14342.924280679199, 14494.782578261545, 14559.490417835324, 14596.956426100141, 14638.634034149232, 13971.790423143864, 14170.504032358818, 14540.818877168509, 14837.40650373973, 14974.0, 14015.159090454605, 14061.789073164115, 14286.214258215898, 14786.778198326818, 14856.851431485547, 10907.0, 11274.718132046764, 11359.943200283946, 11386.43067846621, 11932.70168245766, 10179.98086582155, 11455.699674361102, 11632.767344653228, 11855.703607409883, 11881.821772673244], "value": 8156.003995443498, "unit": "score"}], "name": "dromaeo_css", "value": 7485.2876446452865, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 106.0, 178.0, 138.0, 109.0, 129.0, 105.0, 143.0, 107.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 185.0, 184.0, 183.0, 185.0, 178.0, 181.0, 185.0, 183.0], "value": 183.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 58.0, 108.0, 74.0, 58.0, 66.0, 61.0, 99.0, 57.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 91.0, 72.0, 93.0, 97.0, 128.0, 74.0, 68.0, 101.0], "value": 91.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 88.0, 86.0, 89.0, 86.0, 90.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 114.0, 94.0, 96.0, 95.0, 95.0, 94.0], "value": 96.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 87.0, 85.0, 84.0, 85.0, 84.0, 86.0, 83.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 56.0, 52.0, 54.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 85.0, 77.0, 73.0, 72.0, 71.0, 73.0, 72.0, 74.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 83.0, 82.0, 84.0, 84.0, 83.0, 86.0, 88.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 136.0, 138.0, 140.0, 141.0, 139.0, 118.0, 137.0, 175.0, 134.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 163.0, 164.0, 163.0, 162.0, 145.0, 202.0, 223.0, 181.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 84.0, 83.0, 83.0, 70.0, 65.0, 65.0, 64.0, 65.0], "value": 72.7, "unit": "score"}], "name": "kraken", "value": 1453.9, "alertThreshold": 2.0}]} 09:34:14 INFO - Return code: 0 09:34:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:34:14 ERROR - # TBPL SUCCESS # 09:34:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:34:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:34:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:34:14 INFO - 2016-07-11 09:34:14.821 screenresolution[1805:9964] starting screenresolution argv=screenresolution get 09:34:14 INFO - 2016-07-11 09:34:14.823 screenresolution[1805:9964] Display 0: 1600x1200x32@60 09:34:14 INFO - 2016-07-11 09:34:14.830 screenresolution[1806:9967] starting screenresolution argv=screenresolution list 09:34:14 INFO - Available Modes on Display 0 09:34:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:34:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:34:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:34:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:34:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:34:15 INFO - Intel Iris: 09:34:15 INFO - Chipset Model: Intel Iris 09:34:15 INFO - Type: GPU 09:34:15 INFO - Bus: Built-In 09:34:15 INFO - VRAM (Dynamic, Max): 1536 MB 09:34:15 INFO - Vendor: Intel (0x8086) 09:34:15 INFO - Device ID: 0x0a2e 09:34:15 INFO - Revision ID: 0x0009 09:34:15 INFO - Displays: 09:34:15 INFO - Display: 09:34:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:34:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:34:15 INFO - Display Serial Number: 1600x1200 60 09:34:15 INFO - Main Display: Yes 09:34:15 INFO - Mirror: Off 09:34:15 INFO - Online: Yes 09:34:15 INFO - Rotation: Supported 09:34:15 INFO - Return code: 0 09:34:15 INFO - Running post-action listener: _resource_record_post_action 09:34:15 INFO - ##### 09:34:15 INFO - ##### Finished run-tests step (success) 09:34:15 INFO - ##### 09:34:15 INFO - Running post-run listener: _resource_record_post_run 09:34:15 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 37041664; Write bytes: 710847488; Read time: 234; Write time: 2011 09:34:15 INFO - TinderboxPrint: CPU usage
53.4% 09:34:15 INFO - TinderboxPrint: I/O read bytes / time
37,041,664 / 234 09:34:15 INFO - TinderboxPrint: I/O write bytes / time
710,847,488 / 2,011 09:34:15 INFO - TinderboxPrint: CPU idle
2,261.1 (46.6%) 09:34:15 INFO - TinderboxPrint: CPU system
966.2 (19.9%) 09:34:15 INFO - TinderboxPrint: CPU user
1,622.2 (33.5%) 09:34:15 INFO - TinderboxPrint: Swap in / out
443,543,552 / 0 09:34:15 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390773248; Write bytes: 381954048; Read time: 29104; Write time: 789 09:34:15 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 30789120; Write bytes: 298171904; Read time: 182; Write time: 1153 09:34:15 INFO - Running post-run listener: _upload_blobber_files 09:34:15 INFO - Blob upload gear active. 09:34:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:34:15 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 09:34:15 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'] 09:34:15 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 09:34:15 INFO - (blobuploader) - INFO - Open directory for files ... 09:34:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:34:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:34:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:16 INFO - (blobuploader) - INFO - Done attempting. 09:34:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:34:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:34:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:17 INFO - (blobuploader) - INFO - Done attempting. 09:34:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:34:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:34:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:34:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:34:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:34:20 INFO - (blobuploader) - INFO - Done attempting. 09:34:20 INFO - (blobuploader) - INFO - Iteration through files over. 09:34:20 INFO - Return code: 0 09:34:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:34:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:34:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d99bd6f780c921ce7148d4c7e8037de13dd552cb0b6903a112c36782fd5150571b7ef960e886eabdd34448f2804ef4479f714029550e93680592e271738262a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdfc64721368f9145d43a85065c33bed4345c882b2e936378976e71e60d40f22069a08da1ed9dc002bdbac1fbf3fa8e01772e77ea3588d34347092bb6ca67091", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/13ce073911fdbfcc7f30e42e8ea7bb72fbb1d7699fe9b5b37faea377e47686ddb367e8a34f7351356ded380c7c2263a6e18b1db38a6c6714f9c45a0b3a922cbd"} 09:34:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:34:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:34:20 INFO - Contents: 09:34:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d99bd6f780c921ce7148d4c7e8037de13dd552cb0b6903a112c36782fd5150571b7ef960e886eabdd34448f2804ef4479f714029550e93680592e271738262a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdfc64721368f9145d43a85065c33bed4345c882b2e936378976e71e60d40f22069a08da1ed9dc002bdbac1fbf3fa8e01772e77ea3588d34347092bb6ca67091", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/13ce073911fdbfcc7f30e42e8ea7bb72fbb1d7699fe9b5b37faea377e47686ddb367e8a34f7351356ded380c7c2263a6e18b1db38a6c6714f9c45a0b3a922cbd"} 09:34:20 INFO - Running post-run listener: copy_logs_to_upload_dir 09:34:20 INFO - Copying logs to upload dir... 09:34:20 INFO - mkdir: /builds/slave/test/build/upload/logs 09:34:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.058498 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-11 09:34:20.304365) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 09:34:20.308273) ========= 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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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/d99bd6f780c921ce7148d4c7e8037de13dd552cb0b6903a112c36782fd5150571b7ef960e886eabdd34448f2804ef4479f714029550e93680592e271738262a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdfc64721368f9145d43a85065c33bed4345c882b2e936378976e71e60d40f22069a08da1ed9dc002bdbac1fbf3fa8e01772e77ea3588d34347092bb6ca67091", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/13ce073911fdbfcc7f30e42e8ea7bb72fbb1d7699fe9b5b37faea377e47686ddb367e8a34f7351356ded380c7c2263a6e18b1db38a6c6714f9c45a0b3a922cbd"} build_url:https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011073 build_url: 'https://queue.taskcluster.net/v1/task/CTWVMknITo2XR9vF35AMtg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d99bd6f780c921ce7148d4c7e8037de13dd552cb0b6903a112c36782fd5150571b7ef960e886eabdd34448f2804ef4479f714029550e93680592e271738262a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fdfc64721368f9145d43a85065c33bed4345c882b2e936378976e71e60d40f22069a08da1ed9dc002bdbac1fbf3fa8e01772e77ea3588d34347092bb6ca67091", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/13ce073911fdbfcc7f30e42e8ea7bb72fbb1d7699fe9b5b37faea377e47686ddb367e8a34f7351356ded380c7c2263a6e18b1db38a6c6714f9c45a0b3a922cbd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 09:34:20.342313) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:34:20.342659) ========= 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.pyp3HuSQCN/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.KGyM6Qfgpv/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005047 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 09:34:20.392252) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 09:34:20.392613) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 09:34:20.393005) ========= ========= Total master_lag: 0.18 =========