builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1465475035.08 results: success (0) buildid: 20160609031728 builduid: f734c0de12fb4e6baa6395c6ad4be62c revision: c41a2fe11dda58f58d158abaffd9f205a6a8e21c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.082279) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.082737) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.083048) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.107619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.108055) ========= 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.c13aLqD6hS/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.2ix7NweRFu/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-06-09 05:23:55-- 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% 25.1M=0s 2016-06-09 05:23:55 (25.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124016 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.253990) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.254300) ========= 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.c13aLqD6hS/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.2ix7NweRFu/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.029131 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.303583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:55.303943) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c41a2fe11dda58f58d158abaffd9f205a6a8e21c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c41a2fe11dda58f58d158abaffd9f205a6a8e21c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c13aLqD6hS/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.2ix7NweRFu/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-06-09 05:23:55,380 truncating revision to first 12 chars 2016-06-09 05:23:55,380 Setting DEBUG logging. 2016-06-09 05:23:55,381 attempt 1/10 2016-06-09 05:23:55,381 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c41a2fe11dda?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-09 05:23:56,027 unpacking tar archive at: mozilla-beta-c41a2fe11dda/testing/mozharness/ program finished with exit code 0 elapsedTime=0.905807 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:56.233855) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:56.234173) ========= script_repo_revision: c41a2fe11dda58f58d158abaffd9f205a6a8e21c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:56.234559) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:56.238879) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-09 05:23:56.262564) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-09 05:23:56.262954) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c13aLqD6hS/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.2ix7NweRFu/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:23:56 INFO - MultiFileLogger online at 20160609 05:23:56 in /builds/slave/test 05:23:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:23:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:23:56 INFO - {'append_to_log': False, 05:23:56 INFO - 'base_work_dir': '/builds/slave/test', 05:23:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:23:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:23:56 INFO - 'branch': 'Mozilla-Beta', 05:23:56 INFO - 'buildbot_json_path': 'buildprops.json', 05:23:56 INFO - 'config_files': ('talos/mac_config.py',), 05:23:56 INFO - 'default_actions': ('clobber', 05:23:56 INFO - 'read-buildbot-config', 05:23:56 INFO - 'download-and-extract', 05:23:56 INFO - 'populate-webroot', 05:23:56 INFO - 'create-virtualenv', 05:23:56 INFO - 'install', 05:23:56 INFO - 'run-tests'), 05:23:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:23:56 INFO - 'download_minidump_stackwalk': True, 05:23:56 INFO - 'download_symbols': 'ondemand', 05:23:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:23:56 INFO - 'tooltool.py': '/tools/tooltool.py', 05:23:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:23:56 INFO - '/tools/misc-python/virtualenv.py')}, 05:23:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:23:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:23:56 INFO - 'installer_path': 'installer.exe', 05:23:56 INFO - 'log_level': 'info', 05:23:56 INFO - 'log_name': 'talos', 05:23:56 INFO - 'log_to_console': True, 05:23:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:23:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:23:56 INFO - 'opt_config_files': (), 05:23:56 INFO - 'pip_index': False, 05:23:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:23:56 INFO - 'cmd': ('bash', 05:23:56 INFO - '-c', 05:23:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:23:56 INFO - 'enabled': True, 05:23:56 INFO - 'halt_on_failure': False, 05:23:56 INFO - 'name': 'check_screen_resolution'},), 05:23:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:23:56 INFO - 'cmd': ('bash', 05:23:56 INFO - '-c', 05:23:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:23:56 INFO - 'enabled': True, 05:23:56 INFO - 'halt_on_failure': False, 05:23:56 INFO - 'name': 'check_screen_resolution'},), 05:23:56 INFO - 'run_cmd_checks_enabled': True, 05:23:56 INFO - 'sps_profile': False, 05:23:56 INFO - 'sps_profile_interval': 0, 05:23:56 INFO - 'suite': 'dromaeojs', 05:23:56 INFO - 'system_bits': '32', 05:23:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:23:56 INFO - 'title': 't-yosemite-r7-0019', 05:23:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:23:56 INFO - 'use_talos_json': True, 05:23:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:23:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:23:56 INFO - 'work_dir': 'build'} 05:23:56 INFO - ##### 05:23:56 INFO - ##### Running clobber step. 05:23:56 INFO - ##### 05:23:56 INFO - Running pre-action listener: _resource_record_pre_action 05:23:56 INFO - Running main action method: clobber 05:23:56 INFO - rmtree: /builds/slave/test/build 05:23:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:23:58 INFO - Running post-action listener: _resource_record_post_action 05:23:58 INFO - ##### 05:23:58 INFO - ##### Running read-buildbot-config step. 05:23:58 INFO - ##### 05:23:58 INFO - Running pre-action listener: _resource_record_pre_action 05:23:58 INFO - Running main action method: read_buildbot_config 05:23:58 INFO - Using buildbot properties: 05:23:58 INFO - { 05:23:58 INFO - "project": "", 05:23:58 INFO - "product": "firefox", 05:23:58 INFO - "script_repo_revision": "production", 05:23:58 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 05:23:58 INFO - "repository": "", 05:23:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 05:23:58 INFO - "buildid": "20160609031728", 05:23:58 INFO - "pgo_build": "False", 05:23:58 INFO - "basedir": "/builds/slave/test", 05:23:58 INFO - "buildnumber": 2, 05:23:58 INFO - "slavename": "t-yosemite-r7-0019", 05:23:58 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 05:23:58 INFO - "platform": "yosemite_r7", 05:23:58 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:23:58 INFO - "branch": "mozilla-beta", 05:23:58 INFO - "repo_path": "releases/mozilla-beta", 05:23:58 INFO - "revision": "c41a2fe11dda58f58d158abaffd9f205a6a8e21c", 05:23:58 INFO - "stage_platform": "macosx64", 05:23:58 INFO - "builduid": "f734c0de12fb4e6baa6395c6ad4be62c", 05:23:58 INFO - "slavebuilddir": "test" 05:23:58 INFO - } 05:23:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 05:23:58 INFO - Running post-action listener: _resource_record_post_action 05:23:58 INFO - ##### 05:23:58 INFO - ##### Running download-and-extract step. 05:23:58 INFO - ##### 05:23:58 INFO - Running pre-action listener: _resource_record_pre_action 05:23:58 INFO - Running main action method: download_and_extract 05:23:58 INFO - mkdir: /builds/slave/test/build/tests 05:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:23:58 INFO - https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:23:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:23:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 05:23:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:23:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 05:24:00 INFO - Downloaded 1165 bytes. 05:24:00 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 05:24:00 INFO - Using the following test package requirements: 05:24:00 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:24:00 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:24:00 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 05:24:00 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:24:00 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 05:24:00 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:24:00 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:24:00 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 05:24:00 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 05:24:00 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:24:00 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 05:24:00 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:24:00 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 05:24:00 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:24:00 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 05:24:00 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 05:24:00 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 05:24:00 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 05:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:00 INFO - https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:24:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:24:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:24:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:24:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:24:04 INFO - Downloaded 18292407 bytes. 05:24:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:24:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:24:07 INFO - Return code: 0 05:24:07 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:07 INFO - https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:24:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:24:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 05:24:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 05:24:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 05:24:08 INFO - Downloaded 18292407 bytes. 05:24:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:24:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:24:12 INFO - Return code: 0 05:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:12 INFO - https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:24:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:24:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 05:24:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 05:24:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 05:24:14 INFO - Downloaded 10840145 bytes. 05:24:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:24:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:24:14 INFO - Return code: 0 05:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:14 INFO - https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 05:24:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:24:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:24:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 05:24:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 05:24:21 INFO - Downloaded 86249427 bytes. 05:24:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:24:21 INFO - mkdir: /builds/slave/test/properties 05:24:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:24:21 INFO - Writing to file /builds/slave/test/properties/build_url 05:24:21 INFO - Contents: 05:24:21 INFO - build_url:https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg 05:24:22 INFO - Running post-action listener: _resource_record_post_action 05:24:22 INFO - Running post-action listener: set_extra_try_arguments 05:24:22 INFO - ##### 05:24:22 INFO - ##### Running populate-webroot step. 05:24:22 INFO - ##### 05:24:22 INFO - Running pre-action listener: _resource_record_pre_action 05:24:22 INFO - Running main action method: populate_webroot 05:24:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:24:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:24:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:24:22 INFO - '--tppagecycles', 05:24:22 INFO - '7'], 05:24:22 INFO - 'tests': ['tsvgm']}}, 05:24:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:24:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:24:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:24:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:24:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:24:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['damp', 'tps']}, 05:24:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'tests': ['damp', 'tps']}, 05:24:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['dromaeo_dom']}, 05:24:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:24:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['a11yr', 05:24:22 INFO - 'ts_paint', 05:24:22 INFO - 'tpaint', 05:24:22 INFO - 'sessionrestore', 05:24:22 INFO - 'sessionrestore_no_auto_restore', 05:24:22 INFO - 'tabpaint']}, 05:24:22 INFO - 'other-e10s': {'tests': ['a11yr', 05:24:22 INFO - 'ts_paint', 05:24:22 INFO - 'tpaint', 05:24:22 INFO - 'sessionrestore', 05:24:22 INFO - 'sessionrestore_no_auto_restore', 05:24:22 INFO - 'tabpaint']}, 05:24:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:24:22 INFO - 'ts_paint', 05:24:22 INFO - 'tpaint', 05:24:22 INFO - 'sessionrestore', 05:24:22 INFO - 'sessionrestore_no_auto_restore', 05:24:22 INFO - 'tabpaint']}, 05:24:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:24:22 INFO - 'ts_paint', 05:24:22 INFO - 'tpaint', 05:24:22 INFO - 'sessionrestore', 05:24:22 INFO - 'sessionrestore_no_auto_restore', 05:24:22 INFO - 'tabpaint']}, 05:24:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['a11yr', 05:24:22 INFO - 'ts_paint', 05:24:22 INFO - 'tpaint', 05:24:22 INFO - 'sessionrestore', 05:24:22 INFO - 'sessionrestore_no_auto_restore', 05:24:22 INFO - 'tabpaint']}, 05:24:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['a11yr', 05:24:22 INFO - 'ts_paint', 05:24:22 INFO - 'tpaint', 05:24:22 INFO - 'sessionrestore', 05:24:22 INFO - 'sessionrestore_no_auto_restore', 05:24:22 INFO - 'tabpaint']}, 05:24:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['tsvgx', 05:24:22 INFO - 'tsvgr_opacity', 05:24:22 INFO - 'tart', 05:24:22 INFO - 'tscrollx', 05:24:22 INFO - 'cart']}, 05:24:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:24:22 INFO - 'tsvgr_opacity', 05:24:22 INFO - 'tart', 05:24:22 INFO - 'tscrollx', 05:24:22 INFO - 'cart']}, 05:24:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'talos_options': ['--disable-e10s'], 05:24:22 INFO - 'tests': ['tp5o']}, 05:24:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'tests': ['tp5o']}, 05:24:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'talos_options': ['--disable-e10s', 05:24:22 INFO - '--xperf_path', 05:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:22 INFO - 'tests': ['tp5n']}, 05:24:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:24:22 INFO - 'talos_options': ['--xperf_path', 05:24:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:24:22 INFO - 'tests': ['tp5n']}}, 05:24:22 INFO - 'talos.zip': {'path': '', 05:24:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:24:22 INFO - Running post-action listener: _resource_record_post_action 05:24:22 INFO - ##### 05:24:22 INFO - ##### Running create-virtualenv step. 05:24:22 INFO - ##### 05:24:22 INFO - Running pre-action listener: _resource_record_pre_action 05:24:22 INFO - Running main action method: create_virtualenv 05:24:22 INFO - Creating virtualenv /builds/slave/test/build/venv 05:24:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:24:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:24:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:24:22 INFO - Using real prefix '/tools/python27' 05:24:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:24:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:24:26 INFO - Installing pip.................done. 05:24:26 INFO - Return code: 0 05:24:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:26 INFO - 'HOME': '/Users/cltbld', 05:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:26 INFO - 'LOGNAME': 'cltbld', 05:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:26 INFO - 'MOZ_NO_REMOTE': '1', 05:24:26 INFO - 'NO_EM_RESTART': '1', 05:24:26 INFO - 'PAGER': '/bin/cat', 05:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:26 INFO - 'PWD': '/builds/slave/test', 05:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:26 INFO - 'SHELL': '/bin/bash', 05:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:26 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:26 INFO - 'USER': 'cltbld', 05:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:26 INFO - 'XPC_FLAGS': '0x0', 05:24:26 INFO - 'XPC_SERVICE_NAME': '0', 05:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:24:26 INFO - Cleaning up... 05:24:26 INFO - Return code: 0 05:24:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:24:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:26 INFO - 'HOME': '/Users/cltbld', 05:24:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:26 INFO - 'LOGNAME': 'cltbld', 05:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:26 INFO - 'MOZ_NO_REMOTE': '1', 05:24:26 INFO - 'NO_EM_RESTART': '1', 05:24:26 INFO - 'PAGER': '/bin/cat', 05:24:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:26 INFO - 'PWD': '/builds/slave/test', 05:24:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:26 INFO - 'SHELL': '/bin/bash', 05:24:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:26 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:26 INFO - 'USER': 'cltbld', 05:24:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:26 INFO - 'XPC_FLAGS': '0x0', 05:24:26 INFO - 'XPC_SERVICE_NAME': '0', 05:24:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:27 INFO - Downloading/unpacking psutil>=0.7.1 05:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:24:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:29 INFO - Installing collected packages: psutil 05:24:29 INFO - Running setup.py install for psutil 05:24:29 INFO - building 'psutil._psutil_osx' extension 05:24:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:24:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:24:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:24:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:24:30 INFO - building 'psutil._psutil_posix' extension 05:24:30 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:24:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:24:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:24:30 INFO - ^ 05:24:30 INFO - 1 warning generated. 05:24:30 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:24:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:24:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:24:30 INFO - Successfully installed psutil 05:24:30 INFO - Cleaning up... 05:24:30 INFO - Return code: 0 05:24:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:30 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:30 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.0.0'] in /builds/slave/test/build 05:24:30 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.0.0 05:24:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:30 INFO - 'HOME': '/Users/cltbld', 05:24:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:30 INFO - 'LOGNAME': 'cltbld', 05:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:30 INFO - 'MOZ_NO_REMOTE': '1', 05:24:30 INFO - 'NO_EM_RESTART': '1', 05:24:30 INFO - 'PAGER': '/bin/cat', 05:24:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:30 INFO - 'PWD': '/builds/slave/test', 05:24:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:30 INFO - 'SHELL': '/bin/bash', 05:24:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:30 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:30 INFO - 'USER': 'cltbld', 05:24:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:30 INFO - 'XPC_FLAGS': '0x0', 05:24:30 INFO - 'XPC_SERVICE_NAME': '0', 05:24:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:24:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:24:33 INFO - Installing collected packages: mozsystemmonitor 05:24:33 INFO - Running setup.py install for mozsystemmonitor 05:24:33 INFO - Successfully installed mozsystemmonitor 05:24:33 INFO - Cleaning up... 05:24:33 INFO - Return code: 0 05:24:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:24:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:33 INFO - 'HOME': '/Users/cltbld', 05:24:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:33 INFO - 'LOGNAME': 'cltbld', 05:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:33 INFO - 'MOZ_NO_REMOTE': '1', 05:24:33 INFO - 'NO_EM_RESTART': '1', 05:24:33 INFO - 'PAGER': '/bin/cat', 05:24:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:33 INFO - 'PWD': '/builds/slave/test', 05:24:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:33 INFO - 'SHELL': '/bin/bash', 05:24:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:33 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:33 INFO - 'USER': 'cltbld', 05:24:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:33 INFO - 'XPC_FLAGS': '0x0', 05:24:33 INFO - 'XPC_SERVICE_NAME': '0', 05:24:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:24:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:24:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:24:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:37 INFO - Downloading docopt-0.6.1.tar.gz 05:24:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:24:37 INFO - Installing collected packages: blobuploader, requests, docopt 05:24:37 INFO - Running setup.py install for blobuploader 05:24:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:24:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:24:38 INFO - Running setup.py install for requests 05:24:38 INFO - Running setup.py install for docopt 05:24:38 INFO - Successfully installed blobuploader requests docopt 05:24:38 INFO - Cleaning up... 05:24:38 INFO - Return code: 0 05:24:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:38 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 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:38 INFO - 'HOME': '/Users/cltbld', 05:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:38 INFO - 'LOGNAME': 'cltbld', 05:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:38 INFO - 'MOZ_NO_REMOTE': '1', 05:24:38 INFO - 'NO_EM_RESTART': '1', 05:24:38 INFO - 'PAGER': '/bin/cat', 05:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:38 INFO - 'PWD': '/builds/slave/test', 05:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:38 INFO - 'SHELL': '/bin/bash', 05:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:38 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:38 INFO - 'USER': 'cltbld', 05:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:38 INFO - 'XPC_FLAGS': '0x0', 05:24:38 INFO - 'XPC_SERVICE_NAME': '0', 05:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-vpk6Ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-kNH6fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-YY5Dgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-EDUa6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-PjDRVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-drHx23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-nuKqHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-TMj_bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:39 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Ji61xZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-GHK0IU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-nGD6yD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-c5JBfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-av1ur6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-QkpCeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-wr3alW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-FcPsHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:40 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-jDWtt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:24:40 INFO - Running setup.py install for manifestparser 05:24:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:24:41 INFO - Running setup.py install for mozcrash 05:24:41 INFO - Running setup.py install for mozdebug 05:24:41 INFO - Running setup.py install for mozdevice 05:24:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:24:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:24:41 INFO - Running setup.py install for mozfile 05:24:41 INFO - Running setup.py install for mozhttpd 05:24:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:24:41 INFO - Running setup.py install for mozinfo 05:24:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:24:41 INFO - Running setup.py install for mozInstall 05:24:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for mozleak 05:24:42 INFO - Running setup.py install for mozlog 05:24:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for moznetwork 05:24:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for mozprocess 05:24:42 INFO - Running setup.py install for mozprofile 05:24:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:24:42 INFO - Running setup.py install for mozrunner 05:24:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Running setup.py install for mozscreenshot 05:24:43 INFO - Running setup.py install for moztest 05:24:43 INFO - Running setup.py install for mozversion 05:24:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:24:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:24:43 INFO - Cleaning up... 05:24:43 INFO - Return code: 0 05:24:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24: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')]} 05:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24: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')]} 05:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:43 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 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:43 INFO - 'HOME': '/Users/cltbld', 05:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:43 INFO - 'LOGNAME': 'cltbld', 05:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:43 INFO - 'MOZ_NO_REMOTE': '1', 05:24:43 INFO - 'NO_EM_RESTART': '1', 05:24:43 INFO - 'PAGER': '/bin/cat', 05:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:43 INFO - 'PWD': '/builds/slave/test', 05:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:43 INFO - 'SHELL': '/bin/bash', 05:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:43 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:43 INFO - 'USER': 'cltbld', 05:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:43 INFO - 'XPC_FLAGS': '0x0', 05:24:43 INFO - 'XPC_SERVICE_NAME': '0', 05:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:24:43 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Awhtf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:24:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:24:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:24:43 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-PLmrOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-1PKAob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-n0ZIVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-cya8oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-KLvMp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-drJIs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-rBYZ7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-HddJeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-KWTti0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7LN827-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:24:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:24:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:24:44 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ke_uI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:24:45 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-x5meGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:24:45 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-Y50w_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:45 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-U_LElj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:24:45 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-5DzAyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:24:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:24:45 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-TMaJ9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:24:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:48 INFO - Downloading blessings-1.6.tar.gz 05:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:24:48 INFO - Installing collected packages: blessings 05:24:48 INFO - Running setup.py install for blessings 05:24:48 INFO - Successfully installed blessings 05:24:48 INFO - Cleaning up... 05:24:48 INFO - Return code: 0 05:24:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:24:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:48 INFO - Reading from file tmpfile_stdout 05:24:48 INFO - Current package versions: 05:24:48 INFO - blessings == 1.6 05:24:48 INFO - blobuploader == 1.2.4 05:24:48 INFO - docopt == 0.6.1 05:24:48 INFO - manifestparser == 1.1 05:24:48 INFO - mozInstall == 1.12 05:24:48 INFO - mozcrash == 0.17 05:24:48 INFO - mozdebug == 0.1 05:24:48 INFO - mozdevice == 0.48 05:24:48 INFO - mozfile == 1.2 05:24:48 INFO - mozhttpd == 0.7 05:24:48 INFO - mozinfo == 0.9 05:24:48 INFO - mozleak == 0.1 05:24:48 INFO - mozlog == 3.1 05:24:48 INFO - moznetwork == 0.27 05:24:48 INFO - mozprocess == 0.22 05:24:48 INFO - mozprofile == 0.28 05:24:48 INFO - mozrunner == 6.11 05:24:48 INFO - mozscreenshot == 0.1 05:24:48 INFO - mozsystemmonitor == 0.0 05:24:48 INFO - moztest == 0.7 05:24:48 INFO - mozversion == 1.4 05:24:48 INFO - psutil == 3.1.1 05:24:48 INFO - requests == 1.2.3 05:24:48 INFO - wsgiref == 0.1.2 05:24:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:24:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:49 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 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:24:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:49 INFO - 'HOME': '/Users/cltbld', 05:24:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:49 INFO - 'LOGNAME': 'cltbld', 05:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:49 INFO - 'MOZ_NO_REMOTE': '1', 05:24:49 INFO - 'NO_EM_RESTART': '1', 05:24:49 INFO - 'PAGER': '/bin/cat', 05:24:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:49 INFO - 'PWD': '/builds/slave/test', 05:24:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:49 INFO - 'SHELL': '/bin/bash', 05:24:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:49 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:49 INFO - 'USER': 'cltbld', 05:24:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:49 INFO - 'XPC_FLAGS': '0x0', 05:24:49 INFO - 'XPC_SERVICE_NAME': '0', 05:24:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:24:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:24:49 INFO - Cleaning up... 05:24:49 INFO - Return code: 0 05:24:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:24:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:49 INFO - 'HOME': '/Users/cltbld', 05:24:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:49 INFO - 'LOGNAME': 'cltbld', 05:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:49 INFO - 'MOZ_NO_REMOTE': '1', 05:24:49 INFO - 'NO_EM_RESTART': '1', 05:24:49 INFO - 'PAGER': '/bin/cat', 05:24:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:49 INFO - 'PWD': '/builds/slave/test', 05:24:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:49 INFO - 'SHELL': '/bin/bash', 05:24:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:49 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:49 INFO - 'USER': 'cltbld', 05:24:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:49 INFO - 'XPC_FLAGS': '0x0', 05:24:49 INFO - 'XPC_SERVICE_NAME': '0', 05:24:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:50 INFO - Downloading/unpacking jsonschema 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24: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 05:24:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:24:53 INFO - Downloading/unpacking functools32 (from jsonschema) 05:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:53 INFO - Downloading functools32-3.2.3-2.tar.gz 05:24:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:24:53 INFO - warning: no files found matching '*.txt' 05:24:53 INFO - no previously-included directories found matching 'build' 05:24:53 INFO - no previously-included directories found matching 'dist' 05:24:53 INFO - no previously-included directories found matching '.git*' 05:24:53 INFO - Installing collected packages: jsonschema, functools32 05:24:53 INFO - Running setup.py install for functools32 05:24:53 INFO - warning: no files found matching '*.txt' 05:24:53 INFO - no previously-included directories found matching 'build' 05:24:53 INFO - no previously-included directories found matching 'dist' 05:24:53 INFO - no previously-included directories found matching '.git*' 05:24:53 INFO - Successfully installed jsonschema functools32 05:24:53 INFO - Cleaning up... 05:24:53 INFO - Return code: 0 05:24:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:24:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107fffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10800f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7f98d64a40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, '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.c13aLqD6hS/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.2ix7NweRFu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:24:53 INFO - 'HOME': '/Users/cltbld', 05:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:24:53 INFO - 'LOGNAME': 'cltbld', 05:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:24:53 INFO - 'MOZ_NO_REMOTE': '1', 05:24:53 INFO - 'NO_EM_RESTART': '1', 05:24:53 INFO - 'PAGER': '/bin/cat', 05:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:24:53 INFO - 'PWD': '/builds/slave/test', 05:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:24:53 INFO - 'SHELL': '/bin/bash', 05:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:24:53 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:24:53 INFO - 'USER': 'cltbld', 05:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:24:53 INFO - 'XPC_FLAGS': '0x0', 05:24:53 INFO - 'XPC_SERVICE_NAME': '0', 05:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:24:54 INFO - Downloading/unpacking flake8 05:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:24:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:24:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:24:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:24:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:24:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:24:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:24:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:24:57 INFO - no previously-included directories found matching 'docs/_build' 05:24:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:24:57 INFO - Running setup.py install for pep8 05:24:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:24:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:24:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:24:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:24:57 INFO - no previously-included directories found matching 'docs/_build' 05:24:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:24:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:24:57 INFO - Cleaning up... 05:24:58 INFO - Return code: 0 05:24:58 INFO - Running post-action listener: _resource_record_post_action 05:24:58 INFO - Running post-action listener: _start_resource_monitoring 05:24:58 INFO - Starting resource monitoring. 05:24:58 INFO - ##### 05:24:58 INFO - ##### Running install step. 05:24:58 INFO - ##### 05:24:58 INFO - Running pre-action listener: _resource_record_pre_action 05:24:58 INFO - Running main action method: install 05:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:24:58 INFO - Reading from file tmpfile_stdout 05:24:58 INFO - Detecting whether we're running mozinstall >=1.0... 05:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:24:58 INFO - Reading from file tmpfile_stdout 05:24:58 INFO - Output received: 05:24:58 INFO - Usage: mozinstall [options] installer 05:24:58 INFO - Options: 05:24:58 INFO - -h, --help show this help message and exit 05:24:58 INFO - -d DEST, --destination=DEST 05:24:58 INFO - Directory to install application into. [default: 05:24:58 INFO - "/builds/slave/test"] 05:24:58 INFO - --app=APP Application being installed. [default: firefox] 05:24:58 INFO - mkdir: /builds/slave/test/build/application 05:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:25:19 INFO - Reading from file tmpfile_stdout 05:25:19 INFO - Output received: 05:25:19 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:25:19 INFO - Running post-action listener: _resource_record_post_action 05:25:19 INFO - ##### 05:25:19 INFO - ##### Running run-tests step. 05:25:19 INFO - ##### 05:25:19 INFO - Running pre-action listener: _resource_record_pre_action 05:25:19 INFO - Running main action method: run_tests 05:25:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:19 INFO - 2016-06-09 05:25:19.902 screenresolution[1643:7989] starting screenresolution argv=screenresolution get 05:25:19 INFO - 2016-06-09 05:25:19.905 screenresolution[1643:7989] Display 0: 1600x1200x32@60 05:25:19 INFO - 2016-06-09 05:25:19.910 screenresolution[1644:7991] starting screenresolution argv=screenresolution list 05:25:19 INFO - Available Modes on Display 0 05:25:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:20 INFO - Intel Iris: 05:25:20 INFO - Chipset Model: Intel Iris 05:25:20 INFO - Type: GPU 05:25:20 INFO - Bus: Built-In 05:25:20 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:20 INFO - Vendor: Intel (0x8086) 05:25:20 INFO - Device ID: 0x0a2e 05:25:20 INFO - Revision ID: 0x0009 05:25:20 INFO - Displays: 05:25:20 INFO - Display: 05:25:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:20 INFO - Display Serial Number: 1600x1200 60 05:25:20 INFO - Main Display: Yes 05:25:20 INFO - Mirror: Off 05:25:20 INFO - Online: Yes 05:25:20 INFO - Rotation: Supported 05:25:20 INFO - Return code: 0 05:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:25:20 INFO - Python 2.7.3 05:25:20 INFO - Return code: 0 05:25:20 INFO - grabbing minidump binary from tooltool 05:25:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:25:20 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e9ee00>, '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 0x107d07db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e99b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:25:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:25:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:25:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:25:20 INFO - Return code: 0 05:25:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:25:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:25:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:25:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:25:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:25:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:25:21 INFO - Return code: 0 05:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c13aLqD6hS/Render', 05:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:25:21 INFO - 'HOME': '/Users/cltbld', 05:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:25:21 INFO - 'LOGNAME': 'cltbld', 05:25:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:25:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:25:21 INFO - 'MOZ_NO_REMOTE': '1', 05:25:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:25:21 INFO - 'NO_EM_RESTART': '1', 05:25:21 INFO - 'PAGER': '/bin/cat', 05:25:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:25:21 INFO - 'PWD': '/builds/slave/test', 05:25:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:25:21 INFO - 'SHELL': '/bin/bash', 05:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ix7NweRFu/Listeners', 05:25:21 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 05:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:25:21 INFO - 'USER': 'cltbld', 05:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:25:21 INFO - 'XPC_FLAGS': '0x0', 05:25:21 INFO - 'XPC_SERVICE_NAME': '0', 05:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:25:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:25:21 INFO - mozversion application_buildid: 20160609031728 05:25:21 INFO - mozversion application_changeset: c41a2fe11dda58f58d158abaffd9f205a6a8e21c 05:25:21 INFO - mozversion application_display_name: Firefox 05:25:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:25:21 INFO - mozversion application_name: Firefox 05:25:21 INFO - mozversion application_remotingname: firefox 05:25:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:25:21 INFO - mozversion application_vendor: Mozilla 05:25:21 INFO - mozversion application_version: 48.0 05:25:21 INFO - mozversion platform_buildid: 20160609031728 05:25:21 INFO - mozversion platform_changeset: c41a2fe11dda58f58d158abaffd9f205a6a8e21c 05:25:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:25:21 INFO - mozversion platform_version: 48.0 05:25:21 INFO - using testdate: 1465475121 05:25:21 INFO - actual date: 1465475121 05:25:21 INFO - starting webserver on 'localhost:49276' 05:25:21 INFO - SUITE-START | Running 2 tests 05:25:21 INFO - TEST-START | dromaeo_css 05:25:21 INFO - operating with platform_type : mac_ 05:25:21 INFO - Initialising browser for dromaeo_css test... 05:25:21 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpLRH8px/profile http://localhost:49276/getInfo.html) 05:25:23 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 05:25:23 INFO - PROCESS | 1662 | colorDepth:24 05:25:23 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 05:25:23 INFO - PROCESS | 1662 | __metrics 05:25:23 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:25:23 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:25:24 INFO - TEST-INFO | 1662: exit 0 05:25:24 INFO - Browser initialized. 05:25:24 INFO - Running cycle 1/1 for dromaeo_css test... 05:25:24 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpLRH8px/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:36 INFO - PROCESS | 1666 | RSS: Main: 161267712 05:25:36 INFO - PROCESS | 1666 | 05:26:27 INFO - PROCESS | 1666 | 1465475187893 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:28:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 05:28:39 INFO - PROCESS | 1666 | RSS: Main: 254988288 05:28:39 INFO - PROCESS | 1666 | 05:31:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 05:31:42 INFO - PROCESS | 1666 | RSS: Main: 302489600 05:31:42 INFO - PROCESS | 1666 | 05:34:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 05:34:45 INFO - PROCESS | 1666 | RSS: Main: 305897472 05:34:45 INFO - PROCESS | 1666 | 05:37:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 05:37:47 INFO - PROCESS | 1666 | RSS: Main: 304889856 05:37:47 INFO - PROCESS | 1666 | 05:40:50 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 05:40:51 INFO - PROCESS | 1666 | RSS: Main: 273936384 05:40:51 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 05:43:54 INFO - PROCESS | 1666 | RSS: Main: 315678720 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | __start_tp_report 05:43:54 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:43:54 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:43:54 INFO - PROCESS | 1666 | |i|pagename|runs| 05:43:54 INFO - PROCESS | 1666 | |0;dojo.html;2847.473217454771;3584.6594573515513;3588.74878758487;3606.639336066392;3655.232401195749;5874.530037596993;5887.1463637772495;5887.4995625371785;5894.823155305337;5904.85237869053;5853.824385268449;5938.109283607461;5944.940550594492;5957.910631340523;5967.641941483504;5248.0815857225025;5258.44786297439;5264.341957255353;5332.760025798849;5382.138857782756;4680.602148817355;5334.893302133955;5374.167004114369;5381.569474442035;5389.622726409153;5209.817656382027;5286;5330.653507521999;5350.785968561272;5358.223057656637;5344.35867695877;5384.6499977501335;5384.811531596375;5407.432219616962;5410.864728381783;5150.356205474316;5194.818181363672;5288.101022826128;5315.946840531584;5323.361196656388;5726.885462290731;5777.422257774189;5790.102534107178;5809.854753631151;5813.7383817728305;6277.497800175975;6303.873922521524;6324.525660575475;6325.399087086719;6329.398707122816;6191.504679625664;6230.158928544633;6289.0880822280515;6295.496360291166;6306.779262725782;6004.729787159588;6029.066762097902;6049.892944281268;6053.031514957585;6064.848378790565;5649.983003059407;6332.33510481402;6335.36646335372;6357.078223657544;6371.362863713592;6124.234470691164;6190.690465476709;6281.7173227204885;6304.117423560705;6309.810705678837;6278.121063051177;6279.246490421178;6296.33888441707;6296.433321001132;6309.873802524015;6190.845228869224;6248.125262463259;6250.8124756256475;6252.187215662026;6271.059341098871;6196.132541444202;6237;6288.52836037299;6292.716827742763;6297.4962003039645;5013.924791128136;5040.445550989388;5044.899102017939;5049.596032317406;5065.797368105308;4517.774111294422;4524.705894116872;4530.22986092365;4537.38745269391;4588.426446694163;5059.494050594911;5851.414858514199;5938.138969849348;5991.340952495222;6066.848328791727;5442.265294185314;6145.709401025745;6202.361380959534;6243.282022567463;6262.624242545462;6000.939990600125;6021.849453763691;6034.577579569388;6034.9396506035255;6036.9698151508965;5409.78360865561;5464.426235245322;5522.447755224445;5536.4186760389575;5556.888862222814;6377.394147555975;6434.207126816009;6452.709628066842;6459;6483.643399613066;6240.53196010292;6274.309825919237;6278.874422511615;6286.339934306832;6292.276388215414;6310.905336420049;6468.611883287018;6470.417662410406;6487;6499.28507864144;6282.780102696566;6305.369463053658;6321.936780632135;6339.080833279241;6354.173957385695;6197.380261973766;6205.5345849060595;6275.560710750204;6281.560290779602;6284.057391391328;5014.373203349582;6024.186734790923;6150.384961503813;6294.370562943852;6305.4009869062365;6264.49884009269;6274;6279.120923070683;6294.244690637152;6294.559380843297;6100.328963813889;6107.083937409424;6150.876982460236;6169.043798211284;6172.475339596005;6106.248323414691;6133.417325354084;6216.378362163748;6219.315875253628;6265.96867015662;6145.8156255312415;6172.814815555541;6196.504279657706;6199.597026193283;6202.751889924352 05:43:54 INFO - PROCESS | 1666 | |1;ext.html;543.149970296486;562.5612022622355;606.0000998352717;614.9292831324399;641.3298103481861;31955.520667190012;39680;40621;40740.59259407412;40885.182296354054;45275.32087018689;45712.54287457124;45721.54278457214;45865.77067114668;45912.3113153303;9044.457332560034;10032.899671003306;10056.597736090547;10061.798764024716;10101.747456313578;12016.158868879182;12236.938815305912;12241.44913478891;12243.265404075739;12306.261624302526;11943;12022.398880056006;12048.036157107454;12067.3966301685;12079;10153.847692284622;12032.7593448131;12117;12129.514819407214;12146.574869879512;11763.235389699643;11924.582639607572;11997.220180688228;12224.755504889852;12268.018558515298;42233.78883105596;42529;42549.787251063855;42588.36117458241;42611.36082958758;3350.31318579693;3461.9826900865587;3534.505169276303;3791.7724936503896;4006.4390985262085;28566.857165714246;29179.270518237216;29439;29632;30041.69958300411;2807.242044647954;2818.210900947738;2824;2846.2599724071893;2863.6849946505868;431714;434940;435743;435911;439506;38099.61900380961;38837;62989;63101.05348419777;63145.68427157835;37638.24723505515;37824.62175378211;37894.43158352627;37895.81052094722;37936;15945.282462289224;16246.75629865553;16267.918660406622;16276.51170464888;16297.91851040761;12771.936140319425;12881.742365152644;13726.970477214049;13898.58304250874;13994.860051399559;8510.191531804468;8534.658613655509;8556.74329770108;8621.784455388666;8628.870566941501;3561.7684850484634;3580.534530511017;3586.8299678694534;3589.964100358963;3614.1689808802753;949.9335046546676;1049.3703777733451;1121.9013798097424;1140.3556990300453;1189.1735244005506;31285.530717039263;31373.52939705906;31520.896768613446;32047;32191.839040805113;1076.6608518316705;1164.85439320085;1184.0054354342394;1211.2308683985732;1238.6593686736219;16028.439004634782;16145.59636009109;16148.434804781777;16150.67698646044;16558.751618725728;670329;676167;682974;683374;683378;61974.690126549984;62569;62664;62796.430089247224;62823.05765413523;1278.5780692371495;1439.7768345906402;1570.38754885597;1583.6595132046434;1593.442295196695;26655.66721663948;28434.431311373242;28595.99914003083;29115.7088429113;30623.846880765454;49791.50625481241;50087.24869127039;50089.49910500849;50101.74949125231;50197;460800;461398;461499;462291;462610.8086216966;27333.316667083483;28597.71402286034;29052;29066.418671626026;29084.563731544466;48958.75520622374;49308.50691493183;49500.25749613831;49710;50401;38859.805700971316;39236.01909952274;39947.40078898877;40235;40683.77948661545;37915.81042094772;38803.02992425212;38806.6119338803;39416.80291598639;39645.40531892081;39636.810895673174;39841.80079099586;40071;40269.39595906005;41395;31551.52672709865;32959.14501625035;33201.83399082989;33287.33425331528;33313.666863331055;24306.635400468655;25473.87263063747;25655.486890262462;25694.743052569982;26211.95152193967 05:43:54 INFO - PROCESS | 1666 | |2;jquery.html;398.44417038231666;417.2218811915776;419.50498411873974;422.59430946291576;433.9153864996325;21143.577128457422;21229.68155477667;21413.892930535363;21976.89011554942;21987.450313742163;21623.67564486534;22262.88868555659;22711.54576908461;22991;23072.769272307272;5882.3529411764675;6171.722272497748;6194.907076393858;6203.658798766066;6216.96891515544;5162.716050617215;5490.258815059976;5894.88210235795;6230.376962303779;6230.501559875222;6117.847053823645;6156.230471191101;6163.322034576193;6167.5065994720535;6187.35032821554;5808.215890854723;6021.066734656135;6132.785352512663;6147.47746441553;6157.415045570664;5297.993381257549;5319.281896943901;5325.068113080234;5326.174442961347;5345.385280692732;22426.32721018372;27430.862845685842;27733.029343973067;28039.158825235274;28176.85911570429;2324.0819876148826;2432.841865278769;2466.445049863766;2535.6957165140116;2865.8567071646335;13277.738614831673;15118.848811511854;15732.606684832856;15825.60435989098;16122.355105795872;1780.9020503872282;1819.7270409438559;1865.8693891427606;1901.3155264104912;1938.5153711572107;109545.45227273893;114020.85979140185;116233;116557.83442165554;120534.39732801367;10120.291579589359;11224.831627525431;11382.829257561143;11397.316161030365;11826.053915686725;14656.193909334876;14701.411943522353;14714.0435871668;14730.63173420673;14750.114993100447;8983.146601072887;9068.138526839939;9149.222316103072;9230.16928476427;9264.814703705888;9273.304502162366;9291.76770580741;9304.767380815534;9375.531223438937;9410.71767846952;6897.48268879836;7018.7543435980515;7023.051887995158;7041;7052.576845389191;1671.5988162840831;1977.8318842898227;1980.6434841728628;1997.6304383688773;2023.8482113841521;943.4292253186724;1021.918246540275;1036.212478516333;1080.448971024783;1106.2090605217309;20275.986200689906;20762.792372076387;22260.88869555642;22396.776032239795;22434.10263589471;1060.8833028366873;1064.5635289531256;1090.929089609173;1105.8175401058895;1144.370596172102;11543.595974140864;11913.04695624348;12152;12195.329256890956;12217.511299548096;610221;635433;671380;676934;681341;114714.27928581079;115900.42049789696;117148;120952;121207;1063.1441689440076;1067.802456545526;1083.9078678312273;1087.472575800736;1088.4829705889797;15413.691726165638;19124.521886952938;19623.411297661092;19635.41093767189;19733.21067157355;50226;50940.490595095056;51451.71370715762;51748.93004279787;52917;58892;72461.63769181121;72878;73472;74810;8266.090730019572;8297.087320394872;8359.289460395934;8417.62120704582;8468.407211495136;12402.937985310016;12990.350482476028;13249.403776829873;13304.93347533295;13385.397657105648;11452;11614.825777613174;11647.126465514952;11757.294562326288;11837.940810295893;11316.207865449669;11350.432478376213;11436.085113191091;11521.251118677259;11584.13119016094;11728.76542469163;11820.940895295469;11862.644120676394;11967.76064478723;11984.161108722306;8225.506469611842;8295.336373090258;8310.87533686983;8566.82866342682;8573.18554737299;11121.610743623934;11625.88374116248;11718.355490448108;11830.467628956545;11982.64052078439 05:43:54 INFO - PROCESS | 1666 | |3;mootools.html;1475.4393330534408;1593.099898557315;1617.458151519241;1921.5004098934273;2053.5482193917333;8145.266925976661;8708.956455217723;8836.867446988284;8847.424917380375;8848.115188481148;8625.698100566482;8856.158664926821;8866;8872.20150186483;8876.289896808255;2119.395972147938;2421.092090466075;2532.1643857527065;2535.150724507292;2536.720960694322;2475.789557887582;2510.9748902510923;2520.823542352036;2521.596544552872;2536.264483299841;2485.888135033928;2512.346789834638;2518.9118380856676;2519.5590771614984;2525.4191535946743;2465.3836540864872;2498.2005758157397;2511.296836885675;2524.8990040398544;2530.544501989641;2473.2580225932147;2516.6350879122606;2524.924252272416;2525.5454018276705;2536.2137737301496;8630.913690863073;8826.161514656098;8925.107489251088;8950.41822281556;8974.551272436353;5552.666839989613;5677.261955945742;5712.65724056558;5717.742701578439;5718.942810571924;3582.283543291352;3667.0465678923406;3715.145516531186;3718.925621487555;3746.887593372176;1029.3412216181575;1049.6063976008998;1054.0355574649186;1064.77639695664;1082.7076689293926;11773.705657358549;12082.006468932615;12086.33525156116;12100;12107.091968102428;4682.040181762731;4684.578387945033;4818.975905120522;4839.395075615548;4848.781804818792;9407.670731524364;9420.963693993655;9432.95283523578;9434.622615095319;9438;5246.475352464723;5296.390915044819;5299.894002119936;5302.893942121136;5303.257543943851;4562.520935301812;4726.031163611451;4872.317875497433;4953.777080031407;5073.670211436245;3895.941560876589;3910.393888947218;3982.6216509431556;4000.4199391088127;4016.2569924563463;1068.604616291977;1242.7940405779814;1253.7617852607975;1258.5595041735503;1271.3706715176047;932.3473568502076;967.709687093869;971.8736564246602;987.7036888933292;1007.778288776468;5895.145203945404;6014.36849130835;6056.636601803906;6212;6245.687715614201;1002.9749256268506;1013.640157744005;1024.1653052761976;1026.235454586338;1046.6232156423682;3985.80070996449;4048.919021619551;4050.3721923101966;4127.112670775798;4127.4634297541725;10420.218483613759;10677.252592318464;10770.78458430827;10878.456077196108;10957.780844383067;10745.301555398873;10767.892321076846;10918.727031824268;10950.945245273722;10951.4524273786;7876.921230787733;8208.958955205306;8390.412671112963;8442;8501.744947651581;5907.493589134915;6011.098335249572;6070.939290607037;6114.656031904287;6122.142899993915;9467.952660236655;9795.951020244853;9806.509674516388;9821.508924553887;9832.655857044969;8687.826243474969;9274.25805935537;9772.853407198756;9901.55443005052;9958.701238962843;7584.772456826304;7759.72840950564;7776.377889768692;8376.32989360862;8551.657933682833;7524.473286869867;7625.123110842324;8448.070712221528;8452.239298463168;8452.323814094734;7428.442866784904;7543.43424243173;7547.622618869144;7570.848583028199;7654.966579511694;7409.073865766779;7417.740379086706;7433.9256607433235;7515.060617422822;7635.618219089134;6999.790006299819;7044.260352662896;7044.401225895856;7044.436445084279;7073.610951397723;4916.803327866845;4931.852044438672;5001.974990125026;5018.322526458881;5048.5203905628905;4969.105560999054;5028.371453568304;5033.874153146201;5115.514026167508;5298.072837253389 05:43:54 INFO - PROCESS | 1666 | |4;prototype.html;333.50641051244156;337.202199144777;345.22496994247916;352.3428805278157;353.07494364764307;6461.483081353492;6587.176602924635;6693.163354580677;6731.192256929169;6732.225794033688;6443.420092191702;6571.2771595124495;6612.471002319815;6624.602523848573;6661.600303981764;3831.712621553381;4108.28105081611;4953.975230123844;4963.081829861479;4998.700077995332;4762.571368576828;4841.467438581753;4993.275974983625;5002.474740152268;5004.549590536851;4931.3835770528685;4956.157453232959;4988.551030407262;4990.076835785373;4991.600671946254;4967.652264341497;4978.377702787152;4980.651354405193;5007.298978143063;5008.6744361616375;4259.339802330644;4263.978680106579;4266.850660226908;4271.231178387889;4274.679399045084;5857.882842343172;6385.074164246158;6447.161868957006;6453.096566480696;6481.805545833632;4931.087748766489;5400.810971616014;5869.8239052828485;5873.765049398019;5887.411258874121;7015.263397343308;7046.92953070473;7088.291170882922;7095.006699062135;7103.538270012485;4617.838375656869;4628.398308219944;4646.186917289461;4649.697769644962;4654.604358629521;7185.748498802517;7367.447441441914;7383.114026316876;7406.333429991327;7492.438067144986;4564.771761411983;4587.7017993830295;4591.219492686251;4601.240795268744;4610.792514336796;4701.95298047022;4729.432468103781;4761.381020467386;4768.165571025057;4774.737389443544;5690.402507736628;5701.828945131652;5709.172170035322;5718.571107166896;5719.571032172604;7088.468364872552;7092.680829362691;7092.751753688596;7132.607706576188;7148.857022859514;1540.2606748760659;1559.6023014131324;1624.9675006499804;1633.052829358969;1655.3792327877047;1027.6916924922493;1036.9066783989476;1037.6406906951765;1048.9423081730579;1049.884512703602;6656.966715166393;6661.666916654148;6662.267150613428;6673.265940746514;6688.4649228061635;6640.070390145287;6667.899981500282;7276.781696549112;7301.08736407949;7321.487495875238;5415.53967912724;6617.636030018395;6648.434883034899;6650.437147270388;6667.266600673922;3850.133719912997;3887.863924762676;3894.065424298148;3894.6952770821526;3896.493455850721;7284.635768211674;7669.463137580427;7677.654505547263;7690.961545192238;7693.538387696756;7495.362894154058;7539.8115047123165;7541.622918854036;7574.166841647414;7575.583342916192;7652.349550288177;7653.6938522459595;7654.15804261542;7663.885041724378;7678.961605191938;7096.396190627579;7158.8926166108595;7284.417246620152;7287.417006639562;7317.12194536659;7166.53417527859;7205.459590530626;7215.71137154508;7219.494635375683;7225.891611625725;7669.463137580316;7674.6975907228725;7684.3084122429245;7689.50018248812;7701.730439434593;4312.310030395121;4693.953060469352;4697.624190064855;4699.694519856198;4714.127886341038;6705.437598627312;6986.860262794818;7283.453740969554;7299.233580473974;7309.049823522908;7001.299870012958;7006.75476358325;7023.719051237893;7028.262032486719;7035;6981.301869812978;6989.825254368681;7097.077379940574;7098.609576473344;7110.502264841618;6919.204291506541;6921.792346229621;6931.09895713554;6954.79135625932;6957.686904089227;6801.761938332134;7078.4691148163065;7102.9644851777475;7107.857842843217;7130.5365151265005;6881.208661003849;6936.79189624312;6951.930480695129;6957.965210173917;6967.512274140761 05:43:54 INFO - PROCESS | 1666 | |5;yui.html;698.3086744123319;727.4834867244256;734.0933946575981;760.7717684694591;760.9124950630676;15337.309821058052;15697.686046279081;15714.607134821636;15715.52853414398;15734.685306293895;14300;14493.275336233173;14518.419263229458;14536.3458644361;14551.636209094779;786.3276898251984;807.0517142357736;959.6353385713419;987.8814542254939;992.3450522655049;972.7276362618478;975.0639386189267;980.0836218136066;984.6159997600942;985.3250523391464;831.5094094484253;1051.958561024584;1055.3615062886959;1055.8469021991807;1057.973550661232;1025.1952217509256;1057.9576816927313;1061.9150467962545;1063.3672964586058;1070.2454769387564;800.4756884240832;802.6267785479722;803.116571771053;803.9155888631668;803.9477433966773;15229;15327.157006364645;15329.923350383287;15338.386464541405;15394.307256173499;12871.613851584467;13067.803982940264;13236.801447978289;13328.533501327407;13427.3957671905;10174.389536627752;10265.486725663686;10281.588736450532;10588;10891.83662245067;3579.28414317135;3583.94624080639;3621.5292012038267;3675.2649470105816;3834.9041273968096;84080.15919840845;84115;84311;85596;86801.99999999937;9608.423494590346;9728.124468797841;9910.65312714066;9958.751031224132;9964.10323070927;22520.324390268317;22562.984665690085;22597.4350641232;22666.546669066527;22732.772672273066;7680.270374314431;7818.491798033109;7841.725539606086;8019.43863929531;8036.919630803734;4246.5753424657905;4296.806643701041;4351.8694439166875;4374.4094547236355;4592.609628181575;3805.296020236265;3861.9034524137123;3862.536495620487;3863.16941857502;3876.1666241758157;654.3064351787026;691.1705952856593;715.2346988722018;751.9022527071446;765.2806362019684;402.86504021152655;610.4444955090846;616.1127975714957;634.1344065350855;639.5282234165012;8395.412321137463;8577.914220857836;8614.87077693835;8658.350623703247;8850.401207595081;620.2371083567238;625.5527297981964;625.7246811402969;634.2610858349971;635.6376865186892;3953.9604603954167;3955.940660890089;4058.9835075725205;4081.10215752534;4106.507219133723;92642.61036084464;93394.0660593399;93834.5308273468;95026;95329.57005644921;78197;78328.608356959;78897.60551197207;80419.59790201012;80933.59533202296;65146;65295.673521633034;69183.65408173027;72322;73974.8903766444;7913.762587122395;8132.634031468717;8187.345012399113;8243.80052503848;8265.96675415573;47426.81432964204;48160;48560.5143948556;52692.736536317076;54011;67830.3216967824;68231.97652035124;68374.3162568368;73640;73992.63003684947;11125.610603628835;11473.885261147281;11608.767824643628;11707.531698732297;11713;11910.404479776149;12096.75806483883;12157;12184.817227741769;12242.387880605756;11834.940825296162;11856.940715296369;11857.703557410789;11945.462454189408;12267;10854.40300783433;11575.652730418102;11667.241629294069;11729;11935.582254621047;11374.374409407563;11533.711657208636;11574.6527604172;11697.415129243675;12079.818802717791;9785.461799601091;9787.21702263803;10003.149732272546;10013.549390277309;10280.845787313347;9845.556949937127;9863.01369863008;9901.752456188653;9935.850962235716;10108.94945525297 05:43:54 INFO - PROCESS | 1666 | __end_tp_report 05:43:54 INFO - PROCESS | 1666 | __start_cc_report 05:43:54 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1136 05:43:54 INFO - PROCESS | 1666 | __end_cc_report 05:43:54 INFO - PROCESS | 1666 | __startTimestamp1465476234317__endTimestamp 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:43:54 INFO - PROCESS | 1666 | Number of tests: 6 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5843.44 Median:6124.23 stddev:644.28 (10.5%) stddev-sans-first:601.87 05:43:54 INFO - PROCESS | 1666 | Values: 2847.5 3584.7 3588.7 3606.6 3655.2 5874.5 5887.1 5887.5 5894.8 5904.9 5853.8 5938.1 5944.9 5957.9 5967.6 5248.1 5258.4 5264.3 5332.8 5382.1 4680.6 5334.9 5374.2 5381.6 5389.6 5209.8 5286.0 5330.7 5350.8 5358.2 5344.4 5384.6 5384.8 5407.4 5410.9 5150.4 5194.8 5288.1 5315.9 5323.4 5726.9 5777.4 5790.1 5809.9 5813.7 6277.5 6303.9 6324.5 6325.4 6329.4 6191.5 6230.2 6289.1 6295.5 6306.8 6004.7 6029.1 6049.9 6053.0 6064.8 5650.0 6332.3 6335.4 6357.1 6371.4 6124.2 6190.7 6281.7 6304.1 6309.8 6278.1 6279.2 6296.3 6296.4 6309.9 6190.8 6248.1 6250.8 6252.2 6271.1 6196.1 6237.0 6288.5 6292.7 6297.5 5013.9 5040.4 5044.9 5049.6 5065.8 4517.8 4524.7 4530.2 4537.4 4588.4 5059.5 5851.4 5938.1 5991.3 6066.8 5442.3 6145.7 6202.4 6243.3 6262.6 6000.9 6021.8 6034.6 6034.9 6037.0 5409.8 5464.4 5522.4 5536.4 5556.9 6377.4 6434.2 6452.7 6459.0 6483.6 6240.5 6274.3 6278.9 6286.3 6292.3 6310.9 6468.6 6470.4 6487.0 6499.3 6282.8 6305.4 6321.9 6339.1 6354.2 6197.4 6205.5 6275.6 6281.6 6284.1 5014.4 6024.2 6150.4 6294.4 6305.4 6264.5 6274.0 6279.1 6294.2 6294.6 6100.3 6107.1 6150.9 6169.0 6172.5 6106.2 6133.4 6216.4 6219.3 6266.0 6145.8 6172.8 6196.5 6199.6 6202.8 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:66086.43 Median:3498.24 stddev:143502.87 (4102.1%) stddev-sans-first:143821.06 05:43:54 INFO - PROCESS | 1666 | Values: 543.1 562.6 606.0 614.9 641.3 31955.5 39680.0 40621.0 40740.6 40885.2 45275.3 45712.5 45721.5 45865.8 45912.3 9044.5 10032.9 10056.6 10061.8 10101.7 12016.2 12236.9 12241.4 12243.3 12306.3 11943.0 12022.4 12048.0 12067.4 12079.0 10153.8 12032.8 12117.0 12129.5 12146.6 11763.2 11924.6 11997.2 12224.8 12268.0 42233.8 42529.0 42549.8 42588.4 42611.4 3350.3 3462.0 3534.5 3791.8 4006.4 28566.9 29179.3 29439.0 29632.0 30041.7 2807.2 2818.2 2824.0 2846.3 2863.7 431714.0 434940.0 435743.0 435911.0 439506.0 38099.6 38837.0 62989.0 63101.1 63145.7 37638.2 37824.6 37894.4 37895.8 37936.0 15945.3 16246.8 16267.9 16276.5 16297.9 12771.9 12881.7 13727.0 13898.6 13994.9 8510.2 8534.7 8556.7 8621.8 8628.9 3561.8 3580.5 3586.8 3590.0 3614.2 949.9 1049.4 1121.9 1140.4 1189.2 31285.5 31373.5 31520.9 32047.0 32191.8 1076.7 1164.9 1184.0 1211.2 1238.7 16028.4 16145.6 16148.4 16150.7 16558.8 670329.0 676167.0 682974.0 683374.0 683378.0 61974.7 62569.0 62664.0 62796.4 62823.1 1278.6 1439.8 1570.4 1583.7 1593.4 26655.7 28434.4 28596.0 29115.7 30623.8 49791.5 50087.2 50089.5 50101.7 50197.0 460800.0 461398.0 461499.0 462291.0 462610.8 27333.3 28597.7 29052.0 29066.4 29084.6 48958.8 49308.5 49500.3 49710.0 50401.0 38859.8 39236.0 39947.4 40235.0 40683.8 37915.8 38803.0 38806.6 39416.8 39645.4 39636.8 39841.8 40071.0 40269.4 41395.0 31551.5 32959.1 33201.8 33287.3 33313.7 24306.6 25473.9 25655.5 25694.7 26212.0 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:36556.29 Median:21982.17 stddev:108464.25 (493.4%) stddev-sans-first:108734.54 05:43:54 INFO - PROCESS | 1666 | Values: 398.4 417.2 419.5 422.6 433.9 21143.6 21229.7 21413.9 21976.9 21987.5 21623.7 22262.9 22711.5 22991.0 23072.8 5882.4 6171.7 6194.9 6203.7 6217.0 5162.7 5490.3 5894.9 6230.4 6230.5 6117.8 6156.2 6163.3 6167.5 6187.4 5808.2 6021.1 6132.8 6147.5 6157.4 5298.0 5319.3 5325.1 5326.2 5345.4 22426.3 27430.9 27733.0 28039.2 28176.9 2324.1 2432.8 2466.4 2535.7 2865.9 13277.7 15118.8 15732.6 15825.6 16122.4 1780.9 1819.7 1865.9 1901.3 1938.5 109545.5 114020.9 116233.0 116557.8 120534.4 10120.3 11224.8 11382.8 11397.3 11826.1 14656.2 14701.4 14714.0 14730.6 14750.1 8983.1 9068.1 9149.2 9230.2 9264.8 9273.3 9291.8 9304.8 9375.5 9410.7 6897.5 7018.8 7023.1 7041.0 7052.6 1671.6 1977.8 1980.6 1997.6 2023.8 943.4 1021.9 1036.2 1080.4 1106.2 20276.0 20762.8 22260.9 22396.8 22434.1 1060.9 1064.6 1090.9 1105.8 1144.4 11543.6 11913.0 12152.0 12195.3 12217.5 610221.0 635433.0 671380.0 676934.0 681341.0 114714.3 115900.4 117148.0 120952.0 121207.0 1063.1 1067.8 1083.9 1087.5 1088.5 15413.7 19124.5 19623.4 19635.4 19733.2 50226.0 50940.5 51451.7 51748.9 52917.0 58892.0 72461.6 72878.0 73472.0 74810.0 8266.1 8297.1 8359.3 8417.6 8468.4 12402.9 12990.4 13249.4 13304.9 13385.4 11452.0 11614.8 11647.1 11757.3 11837.9 11316.2 11350.4 11436.1 11521.3 11584.1 11728.8 11820.9 11862.6 11967.8 11984.2 8225.5 8295.3 8310.9 8566.8 8573.2 11121.6 11625.9 11718.4 11830.5 11982.6 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5781.34 Median:5031.12 stddev:3162.88 (62.9%) stddev-sans-first:3155.19 05:43:54 INFO - PROCESS | 1666 | Values: 1475.4 1593.1 1617.5 1921.5 2053.5 8145.3 8709.0 8836.9 8847.4 8848.1 8625.7 8856.2 8866.0 8872.2 8876.3 2119.4 2421.1 2532.2 2535.2 2536.7 2475.8 2511.0 2520.8 2521.6 2536.3 2485.9 2512.3 2518.9 2519.6 2525.4 2465.4 2498.2 2511.3 2524.9 2530.5 2473.3 2516.6 2524.9 2525.5 2536.2 8630.9 8826.2 8925.1 8950.4 8974.6 5552.7 5677.3 5712.7 5717.7 5718.9 3582.3 3667.0 3715.1 3718.9 3746.9 1029.3 1049.6 1054.0 1064.8 1082.7 11773.7 12082.0 12086.3 12100.0 12107.1 4682.0 4684.6 4819.0 4839.4 4848.8 9407.7 9421.0 9433.0 9434.6 9438.0 5246.5 5296.4 5299.9 5302.9 5303.3 4562.5 4726.0 4872.3 4953.8 5073.7 3895.9 3910.4 3982.6 4000.4 4016.3 1068.6 1242.8 1253.8 1258.6 1271.4 932.3 967.7 971.9 987.7 1007.8 5895.1 6014.4 6056.6 6212.0 6245.7 1003.0 1013.6 1024.2 1026.2 1046.6 3985.8 4048.9 4050.4 4127.1 4127.5 10420.2 10677.3 10770.8 10878.5 10957.8 10745.3 10767.9 10918.7 10950.9 10951.5 7876.9 8209.0 8390.4 8442.0 8501.7 5907.5 6011.1 6070.9 6114.7 6122.1 9468.0 9796.0 9806.5 9821.5 9832.7 8687.8 9274.3 9772.9 9901.6 9958.7 7584.8 7759.7 7776.4 8376.3 8551.7 7524.5 7625.1 8448.1 8452.2 8452.3 7428.4 7543.4 7547.6 7570.8 7655.0 7409.1 7417.7 7433.9 7515.1 7635.6 6999.8 7044.3 7044.4 7044.4 7073.6 4916.8 4931.9 5002.0 5018.3 5048.5 4969.1 5028.4 5033.9 5115.5 5298.1 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5798.32 Median:6653.70 stddev:1848.35 (27.8%) stddev-sans-first:1807.45 05:43:54 INFO - PROCESS | 1666 | Values: 333.5 337.2 345.2 352.3 353.1 6461.5 6587.2 6693.2 6731.2 6732.2 6443.4 6571.3 6612.5 6624.6 6661.6 3831.7 4108.3 4954.0 4963.1 4998.7 4762.6 4841.5 4993.3 5002.5 5004.5 4931.4 4956.2 4988.6 4990.1 4991.6 4967.7 4978.4 4980.7 5007.3 5008.7 4259.3 4264.0 4266.9 4271.2 4274.7 5857.9 6385.1 6447.2 6453.1 6481.8 4931.1 5400.8 5869.8 5873.8 5887.4 7015.3 7046.9 7088.3 7095.0 7103.5 4617.8 4628.4 4646.2 4649.7 4654.6 7185.7 7367.4 7383.1 7406.3 7492.4 4564.8 4587.7 4591.2 4601.2 4610.8 4702.0 4729.4 4761.4 4768.2 4774.7 5690.4 5701.8 5709.2 5718.6 5719.6 7088.5 7092.7 7092.8 7132.6 7148.9 1540.3 1559.6 1625.0 1633.1 1655.4 1027.7 1036.9 1037.6 1048.9 1049.9 6657.0 6661.7 6662.3 6673.3 6688.5 6640.1 6667.9 7276.8 7301.1 7321.5 5415.5 6617.6 6648.4 6650.4 6667.3 3850.1 3887.9 3894.1 3894.7 3896.5 7284.6 7669.5 7677.7 7691.0 7693.5 7495.4 7539.8 7541.6 7574.2 7575.6 7652.3 7653.7 7654.2 7663.9 7679.0 7096.4 7158.9 7284.4 7287.4 7317.1 7166.5 7205.5 7215.7 7219.5 7225.9 7669.5 7674.7 7684.3 7689.5 7701.7 4312.3 4694.0 4697.6 4699.7 4714.1 6705.4 6986.9 7283.5 7299.2 7309.0 7001.3 7006.8 7023.7 7028.3 7035.0 6981.3 6989.8 7097.1 7098.6 7110.5 6919.2 6921.8 6931.1 6954.8 6957.7 6801.8 7078.5 7103.0 7107.9 7130.5 6881.2 6936.8 6951.9 6958.0 6967.5 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18787.68 Median:48360.26 stddev:26262.78 (54.3%) stddev-sans-first:26301.33 05:43:54 INFO - PROCESS | 1666 | Values: 698.3 727.5 734.1 760.8 760.9 15337.3 15697.7 15714.6 15715.5 15734.7 14300.0 14493.3 14518.4 14536.3 14551.6 786.3 807.1 959.6 987.9 992.3 972.7 975.1 980.1 984.6 985.3 831.5 1052.0 1055.4 1055.8 1058.0 1025.2 1058.0 1061.9 1063.4 1070.2 800.5 802.6 803.1 803.9 803.9 15229.0 15327.2 15329.9 15338.4 15394.3 12871.6 13067.8 13236.8 13328.5 13427.4 10174.4 10265.5 10281.6 10588.0 10891.8 3579.3 3583.9 3621.5 3675.3 3834.9 84080.2 84115.0 84311.0 85596.0 86802.0 9608.4 9728.1 9910.7 9958.8 9964.1 22520.3 22563.0 22597.4 22666.5 22732.8 7680.3 7818.5 7841.7 8019.4 8036.9 4246.6 4296.8 4351.9 4374.4 4592.6 3805.3 3861.9 3862.5 3863.2 3876.2 654.3 691.2 715.2 751.9 765.3 402.9 610.4 616.1 634.1 639.5 8395.4 8577.9 8614.9 8658.4 8850.4 620.2 625.6 625.7 634.3 635.6 3954.0 3955.9 4059.0 4081.1 4106.5 92642.6 93394.1 93834.5 95026.0 95329.6 78197.0 78328.6 78897.6 80419.6 80933.6 65146.0 65295.7 69183.7 72322.0 73974.9 7913.8 8132.6 8187.3 8243.8 8266.0 47426.8 48160.0 48560.5 52692.7 54011.0 67830.3 68232.0 68374.3 73640.0 73992.6 11125.6 11473.9 11608.8 11707.5 11713.0 11910.4 12096.8 12157.0 12184.8 12242.4 11834.9 11856.9 11857.7 11945.5 12267.0 10854.4 11575.7 11667.2 11729.0 11935.6 11374.4 11533.7 11574.7 11697.4 12079.8 9785.5 9787.2 10003.1 10013.5 10280.8 9845.6 9863.0 9901.8 9935.9 10108.9 05:43:54 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:43:54 INFO - PROCESS | 1666 | 05:43:54 INFO - TEST-INFO | 1666: exit 0 05:43:54 INFO - TEST-OK | dromaeo_css | took 1113279ms 05:43:54 INFO - TEST-START | kraken 05:43:54 INFO - operating with platform_type : mac_ 05:43:54 INFO - Initialising browser for kraken test... 05:43:54 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpsDEtzM/profile http://localhost:49276/getInfo.html) 05:43:56 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 05:43:56 INFO - PROCESS | 1672 | colorDepth:24 05:43:56 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 05:43:56 INFO - PROCESS | 1672 | __metrics 05:43:57 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:43:57 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:43:57 INFO - TEST-INFO | 1672: exit 0 05:43:57 INFO - Browser initialized. 05:43:57 INFO - Running cycle 1/1 for kraken test... 05:43:57 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpsDEtzM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:44:09 INFO - PROCESS | 1675 | RSS: Main: 161574912 05:44:09 INFO - PROCESS | 1675 | 05:44:12 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 05:44:13 INFO - PROCESS | 1675 | RSS: Main: 226496512 05:44:13 INFO - PROCESS | 1675 | 05:44:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 05:44:16 INFO - PROCESS | 1675 | RSS: Main: 241770496 05:44:16 INFO - PROCESS | 1675 | 05:44:19 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 05:44:20 INFO - PROCESS | 1675 | RSS: Main: 324206592 05:44:20 INFO - PROCESS | 1675 | 05:44:22 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 05:44:22 INFO - PROCESS | 1675 | RSS: Main: 230641664 05:44:22 INFO - PROCESS | 1675 | 05:44:25 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:44:25 INFO - PROCESS | 1675 | RSS: Main: 231096320 05:44:25 INFO - PROCESS | 1675 | 05:44:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 05:44:28 INFO - PROCESS | 1675 | RSS: Main: 270647296 05:44:28 INFO - PROCESS | 1675 | 05:44:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 05:44:32 INFO - PROCESS | 1675 | RSS: Main: 269172736 05:44:32 INFO - PROCESS | 1675 | 05:44:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 05:44:35 INFO - PROCESS | 1675 | RSS: Main: 269832192 05:44:35 INFO - PROCESS | 1675 | 05:44:37 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:44:37 INFO - PROCESS | 1675 | RSS: Main: 261099520 05:44:37 INFO - PROCESS | 1675 | 05:44:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:44:40 INFO - PROCESS | 1675 | RSS: Main: 268222464 05:44:40 INFO - PROCESS | 1675 | 05:44:42 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:44:43 INFO - PROCESS | 1675 | RSS: Main: 271351808 05:44:43 INFO - PROCESS | 1675 | 05:44:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:44:46 INFO - PROCESS | 1675 | RSS: Main: 278405120 05:44:46 INFO - PROCESS | 1675 | 05:44:49 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:44:50 INFO - PROCESS | 1675 | RSS: Main: 275419136 05:44:50 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 05:44:51 INFO - PROCESS | 1675 | RSS: Main: 336723968 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | __start_tp_report 05:44:51 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 05:44:51 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 05:44:51 INFO - PROCESS | 1675 | |i|pagename|runs| 05:44:51 INFO - PROCESS | 1675 | |0;ai-astar;113;113;118;113;113;113;112;156;168;170 05:44:51 INFO - PROCESS | 1675 | |1;audio-beat-detection;158;117;142;165;117;139;119;154;155;142 05:44:51 INFO - PROCESS | 1675 | |2;audio-dft;183;186;186;189;207;216;185;187;185;185 05:44:51 INFO - PROCESS | 1675 | |3;audio-fft;71;78;72;161;58;77;82;88;145;56 05:44:51 INFO - PROCESS | 1675 | |4;audio-oscillator;93;91;143;77;79;77;128;77;77;126 05:44:51 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;87;83;87;84;87;84;88;84;85;83 05:44:51 INFO - PROCESS | 1675 | |6;imaging-darkroom;92;91;92;92;92;92;93;91;92;92 05:44:51 INFO - PROCESS | 1675 | |7;imaging-desaturate;87;84;84;84;84;82;86;84;98;131 05:44:51 INFO - PROCESS | 1675 | |8;json-parse-financial;58;62;62;61;59;61;61;79;56;54 05:44:51 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;61;63;62;65;69;74;68;65;63;64 05:44:51 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;77;79;102;103;102;79;78;79;81;83 05:44:51 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;127;127;127;127;112;138;143;137;160;169 05:44:51 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;161;164;161;163;164;161;163;163;162;162 05:44:51 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;68;67;68;65;68;65;69;68;67;65 05:44:51 INFO - PROCESS | 1675 | __end_tp_report 05:44:51 INFO - PROCESS | 1675 | __start_cc_report 05:44:51 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1881 05:44:51 INFO - PROCESS | 1675 | __end_cc_report 05:44:51 INFO - PROCESS | 1675 | __startTimestamp1465476291964__endTimestamp 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | ------- Summary: start ------- 05:44:51 INFO - PROCESS | 1675 | Number of tests: 14 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:128.90 Median:115.50 stddev:24.99 (21.6%) stddev-sans-first:25.84 05:44:51 INFO - PROCESS | 1675 | Values: 113.0 113.0 118.0 113.0 113.0 113.0 112.0 156.0 168.0 170.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:148.00 stddev:17.86 (12.1%) stddev-sans-first:17.83 05:44:51 INFO - PROCESS | 1675 | Values: 158.0 117.0 142.0 165.0 117.0 139.0 119.0 154.0 155.0 142.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:190.90 Median:186.50 stddev:11.17 (6.0%) stddev-sans-first:11.48 05:44:51 INFO - PROCESS | 1675 | Values: 183.0 186.0 186.0 189.0 207.0 216.0 185.0 187.0 185.0 185.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:88.80 Median:74.50 stddev:35.43 (47.6%) stddev-sans-first:36.99 05:44:51 INFO - PROCESS | 1675 | Values: 71.0 78.0 72.0 161.0 58.0 77.0 82.0 88.0 145.0 56.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:77.00 stddev:25.59 (33.2%) stddev-sans-first:27.11 05:44:51 INFO - PROCESS | 1675 | Values: 93.0 91.0 143.0 77.0 79.0 77.0 128.0 77.0 77.0 126.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.87 (2.2%) stddev-sans-first:1.87 05:44:51 INFO - PROCESS | 1675 | Values: 87.0 83.0 87.0 84.0 87.0 84.0 88.0 84.0 85.0 83.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:91.90 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 05:44:51 INFO - PROCESS | 1675 | Values: 92.0 91.0 92.0 92.0 92.0 92.0 93.0 91.0 92.0 92.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:90.40 Median:84.00 stddev:14.95 (17.8%) stddev-sans-first:15.81 05:44:51 INFO - PROCESS | 1675 | Values: 87.0 84.0 84.0 84.0 84.0 82.0 86.0 84.0 98.0 131.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:61.00 stddev:6.77 (11.1%) stddev-sans-first:7.07 05:44:51 INFO - PROCESS | 1675 | Values: 58.0 62.0 62.0 61.0 59.0 61.0 61.0 79.0 56.0 54.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.00 stddev:3.92 (6.0%) stddev-sans-first:3.82 05:44:51 INFO - PROCESS | 1675 | Values: 61.0 63.0 62.0 65.0 69.0 74.0 68.0 65.0 63.0 64.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:79.00 stddev:11.19 (14.2%) stddev-sans-first:11.35 05:44:51 INFO - PROCESS | 1675 | Values: 77.0 79.0 102.0 103.0 102.0 79.0 78.0 79.0 81.0 83.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:137.50 stddev:17.04 (12.4%) stddev-sans-first:17.71 05:44:51 INFO - PROCESS | 1675 | Values: 127.0 127.0 127.0 127.0 112.0 138.0 143.0 137.0 160.0 169.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:163.00 stddev:1.17 (0.7%) stddev-sans-first:1.13 05:44:51 INFO - PROCESS | 1675 | Values: 161.0 164.0 161.0 163.0 164.0 161.0 163.0 163.0 162.0 162.0 05:44:51 INFO - PROCESS | 1675 | 05:44:51 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:68.00 stddev:1.49 (2.2%) stddev-sans-first:1.54 05:44:51 INFO - PROCESS | 1675 | Values: 68.0 67.0 68.0 65.0 68.0 65.0 69.0 68.0 67.0 65.0 05:44:51 INFO - PROCESS | 1675 | -------- Summary: end -------- 05:44:51 INFO - PROCESS | 1675 | 05:44:52 INFO - TEST-INFO | 1675: exit 0 05:44:52 INFO - TEST-OK | kraken | took 57877ms 05:44:52 INFO - SUITE-END | took 1171s 05:44:53 INFO - Completed test suite (00:19:31) 05:44:53 INFO - javascript benchmark 05:44:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2847.473217454771, 3584.6594573515513, 3588.74878758487, 3606.639336066392, 3655.232401195749, 5874.530037596993, 5887.1463637772495, 5887.4995625371785, 5894.823155305337, 5904.85237869053, 5853.824385268449, 5938.109283607461, 5944.940550594492, 5957.910631340523, 5967.641941483504, 5248.0815857225025, 5258.44786297439, 5264.341957255353, 5332.760025798849, 5382.138857782756, 4680.602148817355, 5334.893302133955, 5374.167004114369, 5381.569474442035, 5389.622726409153, 5209.817656382027, 5286.0, 5330.653507521999, 5350.785968561272, 5358.223057656637, 5344.35867695877, 5384.6499977501335, 5384.811531596375, 5407.432219616962, 5410.864728381783, 5150.356205474316, 5194.818181363672, 5288.101022826128, 5315.946840531584, 5323.361196656388, 5726.885462290731, 5777.422257774189, 5790.102534107178, 5809.854753631151, 5813.7383817728305, 6277.497800175975, 6303.873922521524, 6324.525660575475, 6325.399087086719, 6329.398707122816, 6191.504679625664, 6230.158928544633, 6289.0880822280515, 6295.496360291166, 6306.779262725782, 6004.729787159588, 6029.066762097902, 6049.892944281268, 6053.031514957585, 6064.848378790565, 5649.983003059407, 6332.33510481402, 6335.36646335372, 6357.078223657544, 6371.362863713592, 6124.234470691164, 6190.690465476709, 6281.7173227204885, 6304.117423560705, 6309.810705678837, 6278.121063051177, 6279.246490421178, 6296.33888441707, 6296.433321001132, 6309.873802524015, 6190.845228869224, 6248.125262463259, 6250.8124756256475, 6252.187215662026, 6271.059341098871, 6196.132541444202, 6237.0, 6288.52836037299, 6292.716827742763, 6297.4962003039645, 5013.924791128136, 5040.445550989388, 5044.899102017939, 5049.596032317406, 5065.797368105308, 4517.774111294422, 4524.705894116872, 4530.22986092365, 4537.38745269391, 4588.426446694163, 5059.494050594911, 5851.414858514199, 5938.138969849348, 5991.340952495222, 6066.848328791727, 5442.265294185314, 6145.709401025745, 6202.361380959534, 6243.282022567463, 6262.624242545462, 6000.939990600125, 6021.849453763691, 6034.577579569388, 6034.9396506035255, 6036.9698151508965, 5409.78360865561, 5464.426235245322, 5522.447755224445, 5536.4186760389575, 5556.888862222814, 6377.394147555975, 6434.207126816009, 6452.709628066842, 6459.0, 6483.643399613066, 6240.53196010292, 6274.309825919237, 6278.874422511615, 6286.339934306832, 6292.276388215414, 6310.905336420049, 6468.611883287018, 6470.417662410406, 6487.0, 6499.28507864144, 6282.780102696566, 6305.369463053658, 6321.936780632135, 6339.080833279241, 6354.173957385695, 6197.380261973766, 6205.5345849060595, 6275.560710750204, 6281.560290779602, 6284.057391391328, 5014.373203349582, 6024.186734790923, 6150.384961503813, 6294.370562943852, 6305.4009869062365, 6264.49884009269, 6274.0, 6279.120923070683, 6294.244690637152, 6294.559380843297, 6100.328963813889, 6107.083937409424, 6150.876982460236, 6169.043798211284, 6172.475339596005, 6106.248323414691, 6133.417325354084, 6216.378362163748, 6219.315875253628, 6265.96867015662, 6145.8156255312415, 6172.814815555541, 6196.504279657706, 6199.597026193283, 6202.751889924352], "value": 5803.530697060725, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [543.149970296486, 562.5612022622355, 606.0000998352717, 614.9292831324399, 641.3298103481861, 31955.520667190012, 39680.0, 40621.0, 40740.59259407412, 40885.182296354054, 45275.32087018689, 45712.54287457124, 45721.54278457214, 45865.77067114668, 45912.3113153303, 9044.457332560034, 10032.899671003306, 10056.597736090547, 10061.798764024716, 10101.747456313578, 12016.158868879182, 12236.938815305912, 12241.44913478891, 12243.265404075739, 12306.261624302526, 11943.0, 12022.398880056006, 12048.036157107454, 12067.3966301685, 12079.0, 10153.847692284622, 12032.7593448131, 12117.0, 12129.514819407214, 12146.574869879512, 11763.235389699643, 11924.582639607572, 11997.220180688228, 12224.755504889852, 12268.018558515298, 42233.78883105596, 42529.0, 42549.787251063855, 42588.36117458241, 42611.36082958758, 3350.31318579693, 3461.9826900865587, 3534.505169276303, 3791.7724936503896, 4006.4390985262085, 28566.857165714246, 29179.270518237216, 29439.0, 29632.0, 30041.69958300411, 2807.242044647954, 2818.210900947738, 2824.0, 2846.2599724071893, 2863.6849946505868, 431714.0, 434940.0, 435743.0, 435911.0, 439506.0, 38099.61900380961, 38837.0, 62989.0, 63101.05348419777, 63145.68427157835, 37638.24723505515, 37824.62175378211, 37894.43158352627, 37895.81052094722, 37936.0, 15945.282462289224, 16246.75629865553, 16267.918660406622, 16276.51170464888, 16297.91851040761, 12771.936140319425, 12881.742365152644, 13726.970477214049, 13898.58304250874, 13994.860051399559, 8510.191531804468, 8534.658613655509, 8556.74329770108, 8621.784455388666, 8628.870566941501, 3561.7684850484634, 3580.534530511017, 3586.8299678694534, 3589.964100358963, 3614.1689808802753, 949.9335046546676, 1049.3703777733451, 1121.9013798097424, 1140.3556990300453, 1189.1735244005506, 31285.530717039263, 31373.52939705906, 31520.896768613446, 32047.0, 32191.839040805113, 1076.6608518316705, 1164.85439320085, 1184.0054354342394, 1211.2308683985732, 1238.6593686736219, 16028.439004634782, 16145.59636009109, 16148.434804781777, 16150.67698646044, 16558.751618725728, 670329.0, 676167.0, 682974.0, 683374.0, 683378.0, 61974.690126549984, 62569.0, 62664.0, 62796.430089247224, 62823.05765413523, 1278.5780692371495, 1439.7768345906402, 1570.38754885597, 1583.6595132046434, 1593.442295196695, 26655.66721663948, 28434.431311373242, 28595.99914003083, 29115.7088429113, 30623.846880765454, 49791.50625481241, 50087.24869127039, 50089.49910500849, 50101.74949125231, 50197.0, 460800.0, 461398.0, 461499.0, 462291.0, 462610.8086216966, 27333.316667083483, 28597.71402286034, 29052.0, 29066.418671626026, 29084.563731544466, 48958.75520622374, 49308.50691493183, 49500.25749613831, 49710.0, 50401.0, 38859.805700971316, 39236.01909952274, 39947.40078898877, 40235.0, 40683.77948661545, 37915.81042094772, 38803.02992425212, 38806.6119338803, 39416.80291598639, 39645.40531892081, 39636.810895673174, 39841.80079099586, 40071.0, 40269.39595906005, 41395.0, 31551.52672709865, 32959.14501625035, 33201.83399082989, 33287.33425331528, 33313.666863331055, 24306.635400468655, 25473.87263063747, 25655.486890262462, 25694.743052569982, 26211.95152193967], "value": 19602.249146794875, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.44417038231666, 417.2218811915776, 419.50498411873974, 422.59430946291576, 433.9153864996325, 21143.577128457422, 21229.68155477667, 21413.892930535363, 21976.89011554942, 21987.450313742163, 21623.67564486534, 22262.88868555659, 22711.54576908461, 22991.0, 23072.769272307272, 5882.3529411764675, 6171.722272497748, 6194.907076393858, 6203.658798766066, 6216.96891515544, 5162.716050617215, 5490.258815059976, 5894.88210235795, 6230.376962303779, 6230.501559875222, 6117.847053823645, 6156.230471191101, 6163.322034576193, 6167.5065994720535, 6187.35032821554, 5808.215890854723, 6021.066734656135, 6132.785352512663, 6147.47746441553, 6157.415045570664, 5297.993381257549, 5319.281896943901, 5325.068113080234, 5326.174442961347, 5345.385280692732, 22426.32721018372, 27430.862845685842, 27733.029343973067, 28039.158825235274, 28176.85911570429, 2324.0819876148826, 2432.841865278769, 2466.445049863766, 2535.6957165140116, 2865.8567071646335, 13277.738614831673, 15118.848811511854, 15732.606684832856, 15825.60435989098, 16122.355105795872, 1780.9020503872282, 1819.7270409438559, 1865.8693891427606, 1901.3155264104912, 1938.5153711572107, 109545.45227273893, 114020.85979140185, 116233.0, 116557.83442165554, 120534.39732801367, 10120.291579589359, 11224.831627525431, 11382.829257561143, 11397.316161030365, 11826.053915686725, 14656.193909334876, 14701.411943522353, 14714.0435871668, 14730.63173420673, 14750.114993100447, 8983.146601072887, 9068.138526839939, 9149.222316103072, 9230.16928476427, 9264.814703705888, 9273.304502162366, 9291.76770580741, 9304.767380815534, 9375.531223438937, 9410.71767846952, 6897.48268879836, 7018.7543435980515, 7023.051887995158, 7041.0, 7052.576845389191, 1671.5988162840831, 1977.8318842898227, 1980.6434841728628, 1997.6304383688773, 2023.8482113841521, 943.4292253186724, 1021.918246540275, 1036.212478516333, 1080.448971024783, 1106.2090605217309, 20275.986200689906, 20762.792372076387, 22260.88869555642, 22396.776032239795, 22434.10263589471, 1060.8833028366873, 1064.5635289531256, 1090.929089609173, 1105.8175401058895, 1144.370596172102, 11543.595974140864, 11913.04695624348, 12152.0, 12195.329256890956, 12217.511299548096, 610221.0, 635433.0, 671380.0, 676934.0, 681341.0, 114714.27928581079, 115900.42049789696, 117148.0, 120952.0, 121207.0, 1063.1441689440076, 1067.802456545526, 1083.9078678312273, 1087.472575800736, 1088.4829705889797, 15413.691726165638, 19124.521886952938, 19623.411297661092, 19635.41093767189, 19733.21067157355, 50226.0, 50940.490595095056, 51451.71370715762, 51748.93004279787, 52917.0, 58892.0, 72461.63769181121, 72878.0, 73472.0, 74810.0, 8266.090730019572, 8297.087320394872, 8359.289460395934, 8417.62120704582, 8468.407211495136, 12402.937985310016, 12990.350482476028, 13249.403776829873, 13304.93347533295, 13385.397657105648, 11452.0, 11614.825777613174, 11647.126465514952, 11757.294562326288, 11837.940810295893, 11316.207865449669, 11350.432478376213, 11436.085113191091, 11521.251118677259, 11584.13119016094, 11728.76542469163, 11820.940895295469, 11862.644120676394, 11967.76064478723, 11984.161108722306, 8225.506469611842, 8295.336373090258, 8310.87533686983, 8566.82866342682, 8573.18554737299, 11121.610743623934, 11625.88374116248, 11718.355490448108, 11830.467628956545, 11982.64052078439], "value": 10149.398589852033, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.4393330534408, 1593.099898557315, 1617.458151519241, 1921.5004098934273, 2053.5482193917333, 8145.266925976661, 8708.956455217723, 8836.867446988284, 8847.424917380375, 8848.115188481148, 8625.698100566482, 8856.158664926821, 8866.0, 8872.20150186483, 8876.289896808255, 2119.395972147938, 2421.092090466075, 2532.1643857527065, 2535.150724507292, 2536.720960694322, 2475.789557887582, 2510.9748902510923, 2520.823542352036, 2521.596544552872, 2536.264483299841, 2485.888135033928, 2512.346789834638, 2518.9118380856676, 2519.5590771614984, 2525.4191535946743, 2465.3836540864872, 2498.2005758157397, 2511.296836885675, 2524.8990040398544, 2530.544501989641, 2473.2580225932147, 2516.6350879122606, 2524.924252272416, 2525.5454018276705, 2536.2137737301496, 8630.913690863073, 8826.161514656098, 8925.107489251088, 8950.41822281556, 8974.551272436353, 5552.666839989613, 5677.261955945742, 5712.65724056558, 5717.742701578439, 5718.942810571924, 3582.283543291352, 3667.0465678923406, 3715.145516531186, 3718.925621487555, 3746.887593372176, 1029.3412216181575, 1049.6063976008998, 1054.0355574649186, 1064.77639695664, 1082.7076689293926, 11773.705657358549, 12082.006468932615, 12086.33525156116, 12100.0, 12107.091968102428, 4682.040181762731, 4684.578387945033, 4818.975905120522, 4839.395075615548, 4848.781804818792, 9407.670731524364, 9420.963693993655, 9432.95283523578, 9434.622615095319, 9438.0, 5246.475352464723, 5296.390915044819, 5299.894002119936, 5302.893942121136, 5303.257543943851, 4562.520935301812, 4726.031163611451, 4872.317875497433, 4953.777080031407, 5073.670211436245, 3895.941560876589, 3910.393888947218, 3982.6216509431556, 4000.4199391088127, 4016.2569924563463, 1068.604616291977, 1242.7940405779814, 1253.7617852607975, 1258.5595041735503, 1271.3706715176047, 932.3473568502076, 967.709687093869, 971.8736564246602, 987.7036888933292, 1007.778288776468, 5895.145203945404, 6014.36849130835, 6056.636601803906, 6212.0, 6245.687715614201, 1002.9749256268506, 1013.640157744005, 1024.1653052761976, 1026.235454586338, 1046.6232156423682, 3985.80070996449, 4048.919021619551, 4050.3721923101966, 4127.112670775798, 4127.4634297541725, 10420.218483613759, 10677.252592318464, 10770.78458430827, 10878.456077196108, 10957.780844383067, 10745.301555398873, 10767.892321076846, 10918.727031824268, 10950.945245273722, 10951.4524273786, 7876.921230787733, 8208.958955205306, 8390.412671112963, 8442.0, 8501.744947651581, 5907.493589134915, 6011.098335249572, 6070.939290607037, 6114.656031904287, 6122.142899993915, 9467.952660236655, 9795.951020244853, 9806.509674516388, 9821.508924553887, 9832.655857044969, 8687.826243474969, 9274.25805935537, 9772.853407198756, 9901.55443005052, 9958.701238962843, 7584.772456826304, 7759.72840950564, 7776.377889768692, 8376.32989360862, 8551.657933682833, 7524.473286869867, 7625.123110842324, 8448.070712221528, 8452.239298463168, 8452.323814094734, 7428.442866784904, 7543.43424243173, 7547.622618869144, 7570.848583028199, 7654.966579511694, 7409.073865766779, 7417.740379086706, 7433.9256607433235, 7515.060617422822, 7635.618219089134, 6999.790006299819, 7044.260352662896, 7044.401225895856, 7044.436445084279, 7073.610951397723, 4916.803327866845, 4931.852044438672, 5001.974990125026, 5018.322526458881, 5048.5203905628905, 4969.105560999054, 5028.371453568304, 5033.874153146201, 5115.514026167508, 5298.072837253389], "value": 4691.703613268663, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.50641051244156, 337.202199144777, 345.22496994247916, 352.3428805278157, 353.07494364764307, 6461.483081353492, 6587.176602924635, 6693.163354580677, 6731.192256929169, 6732.225794033688, 6443.420092191702, 6571.2771595124495, 6612.471002319815, 6624.602523848573, 6661.600303981764, 3831.712621553381, 4108.28105081611, 4953.975230123844, 4963.081829861479, 4998.700077995332, 4762.571368576828, 4841.467438581753, 4993.275974983625, 5002.474740152268, 5004.549590536851, 4931.3835770528685, 4956.157453232959, 4988.551030407262, 4990.076835785373, 4991.600671946254, 4967.652264341497, 4978.377702787152, 4980.651354405193, 5007.298978143063, 5008.6744361616375, 4259.339802330644, 4263.978680106579, 4266.850660226908, 4271.231178387889, 4274.679399045084, 5857.882842343172, 6385.074164246158, 6447.161868957006, 6453.096566480696, 6481.805545833632, 4931.087748766489, 5400.810971616014, 5869.8239052828485, 5873.765049398019, 5887.411258874121, 7015.263397343308, 7046.92953070473, 7088.291170882922, 7095.006699062135, 7103.538270012485, 4617.838375656869, 4628.398308219944, 4646.186917289461, 4649.697769644962, 4654.604358629521, 7185.748498802517, 7367.447441441914, 7383.114026316876, 7406.333429991327, 7492.438067144986, 4564.771761411983, 4587.7017993830295, 4591.219492686251, 4601.240795268744, 4610.792514336796, 4701.95298047022, 4729.432468103781, 4761.381020467386, 4768.165571025057, 4774.737389443544, 5690.402507736628, 5701.828945131652, 5709.172170035322, 5718.571107166896, 5719.571032172604, 7088.468364872552, 7092.680829362691, 7092.751753688596, 7132.607706576188, 7148.857022859514, 1540.2606748760659, 1559.6023014131324, 1624.9675006499804, 1633.052829358969, 1655.3792327877047, 1027.6916924922493, 1036.9066783989476, 1037.6406906951765, 1048.9423081730579, 1049.884512703602, 6656.966715166393, 6661.666916654148, 6662.267150613428, 6673.265940746514, 6688.4649228061635, 6640.070390145287, 6667.899981500282, 7276.781696549112, 7301.08736407949, 7321.487495875238, 5415.53967912724, 6617.636030018395, 6648.434883034899, 6650.437147270388, 6667.266600673922, 3850.133719912997, 3887.863924762676, 3894.065424298148, 3894.6952770821526, 3896.493455850721, 7284.635768211674, 7669.463137580427, 7677.654505547263, 7690.961545192238, 7693.538387696756, 7495.362894154058, 7539.8115047123165, 7541.622918854036, 7574.166841647414, 7575.583342916192, 7652.349550288177, 7653.6938522459595, 7654.15804261542, 7663.885041724378, 7678.961605191938, 7096.396190627579, 7158.8926166108595, 7284.417246620152, 7287.417006639562, 7317.12194536659, 7166.53417527859, 7205.459590530626, 7215.71137154508, 7219.494635375683, 7225.891611625725, 7669.463137580316, 7674.6975907228725, 7684.3084122429245, 7689.50018248812, 7701.730439434593, 4312.310030395121, 4693.953060469352, 4697.624190064855, 4699.694519856198, 4714.127886341038, 6705.437598627312, 6986.860262794818, 7283.453740969554, 7299.233580473974, 7309.049823522908, 7001.299870012958, 7006.75476358325, 7023.719051237893, 7028.262032486719, 7035.0, 6981.301869812978, 6989.825254368681, 7097.077379940574, 7098.609576473344, 7110.502264841618, 6919.204291506541, 6921.792346229621, 6931.09895713554, 6954.79135625932, 6957.686904089227, 6801.761938332134, 7078.4691148163065, 7102.9644851777475, 7107.857842843217, 7130.5365151265005, 6881.208661003849, 6936.79189624312, 6951.930480695129, 6957.965210173917, 6967.512274140761], "value": 5178.386894610949, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3086744123319, 727.4834867244256, 734.0933946575981, 760.7717684694591, 760.9124950630676, 15337.309821058052, 15697.686046279081, 15714.607134821636, 15715.52853414398, 15734.685306293895, 14300.0, 14493.275336233173, 14518.419263229458, 14536.3458644361, 14551.636209094779, 786.3276898251984, 807.0517142357736, 959.6353385713419, 987.8814542254939, 992.3450522655049, 972.7276362618478, 975.0639386189267, 980.0836218136066, 984.6159997600942, 985.3250523391464, 831.5094094484253, 1051.958561024584, 1055.3615062886959, 1055.8469021991807, 1057.973550661232, 1025.1952217509256, 1057.9576816927313, 1061.9150467962545, 1063.3672964586058, 1070.2454769387564, 800.4756884240832, 802.6267785479722, 803.116571771053, 803.9155888631668, 803.9477433966773, 15229.0, 15327.157006364645, 15329.923350383287, 15338.386464541405, 15394.307256173499, 12871.613851584467, 13067.803982940264, 13236.801447978289, 13328.533501327407, 13427.3957671905, 10174.389536627752, 10265.486725663686, 10281.588736450532, 10588.0, 10891.83662245067, 3579.28414317135, 3583.94624080639, 3621.5292012038267, 3675.2649470105816, 3834.9041273968096, 84080.15919840845, 84115.0, 84311.0, 85596.0, 86801.99999999937, 9608.423494590346, 9728.124468797841, 9910.65312714066, 9958.751031224132, 9964.10323070927, 22520.324390268317, 22562.984665690085, 22597.4350641232, 22666.546669066527, 22732.772672273066, 7680.270374314431, 7818.491798033109, 7841.725539606086, 8019.43863929531, 8036.919630803734, 4246.5753424657905, 4296.806643701041, 4351.8694439166875, 4374.4094547236355, 4592.609628181575, 3805.296020236265, 3861.9034524137123, 3862.536495620487, 3863.16941857502, 3876.1666241758157, 654.3064351787026, 691.1705952856593, 715.2346988722018, 751.9022527071446, 765.2806362019684, 402.86504021152655, 610.4444955090846, 616.1127975714957, 634.1344065350855, 639.5282234165012, 8395.412321137463, 8577.914220857836, 8614.87077693835, 8658.350623703247, 8850.401207595081, 620.2371083567238, 625.5527297981964, 625.7246811402969, 634.2610858349971, 635.6376865186892, 3953.9604603954167, 3955.940660890089, 4058.9835075725205, 4081.10215752534, 4106.507219133723, 92642.61036084464, 93394.0660593399, 93834.5308273468, 95026.0, 95329.57005644921, 78197.0, 78328.608356959, 78897.60551197207, 80419.59790201012, 80933.59533202296, 65146.0, 65295.673521633034, 69183.65408173027, 72322.0, 73974.8903766444, 7913.762587122395, 8132.634031468717, 8187.345012399113, 8243.80052503848, 8265.96675415573, 47426.81432964204, 48160.0, 48560.5143948556, 52692.736536317076, 54011.0, 67830.3216967824, 68231.97652035124, 68374.3162568368, 73640.0, 73992.63003684947, 11125.610603628835, 11473.885261147281, 11608.767824643628, 11707.531698732297, 11713.0, 11910.404479776149, 12096.75806483883, 12157.0, 12184.817227741769, 12242.387880605756, 11834.940825296162, 11856.940715296369, 11857.703557410789, 11945.462454189408, 12267.0, 10854.40300783433, 11575.652730418102, 11667.241629294069, 11729.0, 11935.582254621047, 11374.374409407563, 11533.711657208636, 11574.6527604172, 11697.415129243675, 12079.818802717791, 9785.461799601091, 9787.21702263803, 10003.149732272546, 10013.549390277309, 10280.845787313347, 9845.556949937127, 9863.01369863008, 9901.752456188653, 9935.850962235716, 10108.94945525297], "value": 7203.484383169936, "unit": "score"}], "name": "dromaeo_css", "value": 7660.49945992476, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 118.0, 113.0, 113.0, 113.0, 112.0, 156.0, 168.0, 170.0], "value": 128.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 117.0, 142.0, 165.0, 117.0, 139.0, 119.0, 154.0, 155.0, 142.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 186.0, 189.0, 207.0, 216.0, 185.0, 187.0, 185.0, 185.0], "value": 190.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 72.0, 161.0, 58.0, 77.0, 82.0, 88.0, 145.0, 56.0], "value": 88.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 143.0, 77.0, 79.0, 77.0, 128.0, 77.0, 77.0, 126.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 84.0, 87.0, 84.0, 88.0, 84.0, 85.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 92.0, 92.0, 92.0, 93.0, 91.0, 92.0, 92.0], "value": 91.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 84.0, 84.0, 84.0, 82.0, 86.0, 84.0, 98.0, 131.0], "value": 90.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 62.0, 61.0, 59.0, 61.0, 61.0, 79.0, 56.0, 54.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 65.0, 69.0, 74.0, 68.0, 65.0, 63.0, 64.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 102.0, 103.0, 102.0, 79.0, 78.0, 79.0, 81.0, 83.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 127.0, 112.0, 138.0, 143.0, 137.0, 160.0, 169.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 161.0, 163.0, 164.0, 161.0, 163.0, 163.0, 162.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 65.0, 68.0, 65.0, 69.0, 68.0, 67.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1492.8, "alertThreshold": 2.0}]} 05:44:53 INFO - Return code: 0 05:44:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:44:53 ERROR - # TBPL SUCCESS # 05:44:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:44:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:44:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:44:53 INFO - 2016-06-09 05:44:53.259 screenresolution[1679:10075] starting screenresolution argv=screenresolution get 05:44:53 INFO - 2016-06-09 05:44:53.262 screenresolution[1679:10075] Display 0: 1600x1200x32@60 05:44:53 INFO - 2016-06-09 05:44:53.268 screenresolution[1680:10078] starting screenresolution argv=screenresolution list 05:44:53 INFO - Available Modes on Display 0 05:44:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:44:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:44:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:44:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:44:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:44:53 INFO - Intel Iris: 05:44:53 INFO - Chipset Model: Intel Iris 05:44:53 INFO - Type: GPU 05:44:53 INFO - Bus: Built-In 05:44:53 INFO - VRAM (Dynamic, Max): 1536 MB 05:44:53 INFO - Vendor: Intel (0x8086) 05:44:53 INFO - Device ID: 0x0a2e 05:44:53 INFO - Revision ID: 0x0009 05:44:53 INFO - Displays: 05:44:53 INFO - Display: 05:44:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:44:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:44:53 INFO - Display Serial Number: 1600x1200 60 05:44:53 INFO - Main Display: Yes 05:44:53 INFO - Mirror: Off 05:44:53 INFO - Online: Yes 05:44:53 INFO - Rotation: Supported 05:44:53 INFO - Return code: 0 05:44:53 INFO - Running post-action listener: _resource_record_post_action 05:44:53 INFO - Running post-run listener: _resource_record_post_run 05:44:54 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 32673280; Write bytes: 493392896; Read time: 315; Write time: 1863 05:44:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 186032640; Write bytes: 186938880; Read time: 16550; Write time: 375 05:44:54 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 31751680; Write bytes: 296500736; Read time: 302; Write time: 1454 05:44:54 INFO - Running post-run listener: _upload_blobber_files 05:44:54 INFO - Blob upload gear active. 05:44:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:44:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:44:54 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:44:54 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:44:54 INFO - (blobuploader) - INFO - Open directory for files ... 05:44:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:44:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:44:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:55 INFO - (blobuploader) - INFO - Done attempting. 05:44:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:44:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:44:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:44:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:44:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:44:56 INFO - (blobuploader) - INFO - Done attempting. 05:44:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:44:56 INFO - Return code: 0 05:44:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:44:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:44:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a48ea2ae3adb360a828f340d73b0a45e037a0aa689e21f73360f6f5c90a55f782704504330d89cdd05a3a69538a00b3eb3963a7d528deadd64e66ea3dc89edcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9276d06fdb4a4cc9806e8a33916fcd5bea7752f3275990154e852d8d0544ec8b6adb1770ae5b4c68f5bd83c23f718dadad4142a1891c69071fd60928e89681d5"} 05:44:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:44:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:44:56 INFO - Contents: 05:44:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a48ea2ae3adb360a828f340d73b0a45e037a0aa689e21f73360f6f5c90a55f782704504330d89cdd05a3a69538a00b3eb3963a7d528deadd64e66ea3dc89edcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9276d06fdb4a4cc9806e8a33916fcd5bea7752f3275990154e852d8d0544ec8b6adb1770ae5b4c68f5bd83c23f718dadad4142a1891c69071fd60928e89681d5"} 05:44:56 INFO - Running post-run listener: copy_logs_to_upload_dir 05:44:56 INFO - Copying logs to upload dir... 05:44:56 INFO - mkdir: /builds/slave/test/build/upload/logs 05:44:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.337130 ========= master_lag: 0.58 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-06-09 05:44:57.180784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 05:44:57.188121) ========= 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.c13aLqD6hS/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.2ix7NweRFu/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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/Mozilla-Beta/sha512/a48ea2ae3adb360a828f340d73b0a45e037a0aa689e21f73360f6f5c90a55f782704504330d89cdd05a3a69538a00b3eb3963a7d528deadd64e66ea3dc89edcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9276d06fdb4a4cc9806e8a33916fcd5bea7752f3275990154e852d8d0544ec8b6adb1770ae5b4c68f5bd83c23f718dadad4142a1891c69071fd60928e89681d5"} build_url:https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://queue.taskcluster.net/v1/task/a53ZAbIuSxiNihUanuC_wg/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a48ea2ae3adb360a828f340d73b0a45e037a0aa689e21f73360f6f5c90a55f782704504330d89cdd05a3a69538a00b3eb3963a7d528deadd64e66ea3dc89edcf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9276d06fdb4a4cc9806e8a33916fcd5bea7752f3275990154e852d8d0544ec8b6adb1770ae5b4c68f5bd83c23f718dadad4142a1891c69071fd60928e89681d5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-09 05:44:57.223589) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:44:57.223947) ========= 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.c13aLqD6hS/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.2ix7NweRFu/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-09 05:44:57.272925) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 05:44:57.273236) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-09 05:44:57.273567) ========= ========= Total master_lag: 0.71 =========