builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0367 starttime: 1492369311.75 results: success (0) buildid: 20170416110106 builduid: 3e5c7d46a6144eb38fa34dbfbbc58e6d revision: c697e756f738ce37abc56f31bfbc48f55625d617 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:51.749188) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:51.749632) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:51.749990) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:51.777490) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:51.777997) ========= 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.LmxF1PXh6V/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.EokiCmZl1c/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 --2017-04-16 12:01:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 70.0M=0s 2017-04-16 12:01:51 (70.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.182515 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:51.984916) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:51.985255) ========= 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.LmxF1PXh6V/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.EokiCmZl1c/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.026835 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:52.034415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-16 12:01:52.034991) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c697e756f738ce37abc56f31bfbc48f55625d617 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c697e756f738ce37abc56f31bfbc48f55625d617 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LmxF1PXh6V/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.EokiCmZl1c/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 2017-04-16 12:01:52,143 truncating revision to first 12 chars 2017-04-16 12:01:52,143 Setting DEBUG logging. 2017-04-16 12:01:52,143 attempt 1/10 2017-04-16 12:01:52,143 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c697e756f738?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-16 12:01:53,687 unpacking tar archive at: mozilla-central-c697e756f738/testing/mozharness/ program finished with exit code 0 elapsedTime=1.817757 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-16 12:01:53.877389) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:53.877693) ========= script_repo_revision: c697e756f738ce37abc56f31bfbc48f55625d617 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:53.878112) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:53.878377) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-16 12:01:53.898211) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-16 12:01:53.898499) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LmxF1PXh6V/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.EokiCmZl1c/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:01:54 INFO - MultiFileLogger online at 20170416 12:01:54 in /builds/slave/test 12:01:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:01:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:01:54 INFO - {'append_to_log': False, 12:01:54 INFO - 'base_work_dir': '/builds/slave/test', 12:01:54 INFO - 'blob_upload_branch': 'Firefox', 12:01:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:01:54 INFO - 'branch': 'Firefox', 12:01:54 INFO - 'buildbot_json_path': 'buildprops.json', 12:01:54 INFO - 'config_files': ('talos/mac_config.py',), 12:01:54 INFO - 'default_actions': ('clobber', 12:01:54 INFO - 'read-buildbot-config', 12:01:54 INFO - 'download-and-extract', 12:01:54 INFO - 'populate-webroot', 12:01:54 INFO - 'create-virtualenv', 12:01:54 INFO - 'install', 12:01:54 INFO - 'run-tests'), 12:01:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:01:54 INFO - 'download_minidump_stackwalk': True, 12:01:54 INFO - 'download_symbols': 'ondemand', 12:01:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:01:54 INFO - 'tooltool.py': '/tools/tooltool.py', 12:01:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:01:54 INFO - '/tools/misc-python/virtualenv.py')}, 12:01:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:01:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:01:54 INFO - 'gecko_profile': False, 12:01:54 INFO - 'gecko_profile_interval': 0, 12:01:54 INFO - 'installer_path': 'installer.exe', 12:01:54 INFO - 'log_level': 'info', 12:01:54 INFO - 'log_name': 'talos', 12:01:54 INFO - 'log_to_console': True, 12:01:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:01:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:01:54 INFO - 'opt_config_files': (), 12:01:54 INFO - 'pip_index': False, 12:01:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:01:54 INFO - 'cmd': ('bash', 12:01:54 INFO - '-c', 12:01:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:01:54 INFO - 'enabled': True, 12:01:54 INFO - 'halt_on_failure': False, 12:01:54 INFO - 'name': 'check_screen_resolution'},), 12:01:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:01:54 INFO - 'cmd': ('bash', 12:01:54 INFO - '-c', 12:01:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:01:54 INFO - 'enabled': True, 12:01:54 INFO - 'halt_on_failure': False, 12:01:54 INFO - 'name': 'check_screen_resolution'},), 12:01:54 INFO - 'run_cmd_checks_enabled': True, 12:01:54 INFO - 'suite': 'dromaeojs', 12:01:54 INFO - 'system_bits': '32', 12:01:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:01:54 INFO - 'title': 't-yosemite-r7-0367', 12:01:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:01:54 INFO - 'use_talos_json': True, 12:01:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:01:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:01:54 INFO - 'work_dir': 'build'} 12:01:54 INFO - [mozharness: 2017-04-16 19:01:54.112236Z] Running clobber step. 12:01:54 INFO - Running pre-action listener: _resource_record_pre_action 12:01:54 INFO - Running main action method: clobber 12:01:54 INFO - rmtree: /builds/slave/test/build 12:01:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:01:56 INFO - Running post-action listener: _resource_record_post_action 12:01:56 INFO - [mozharness: 2017-04-16 19:01:56.703956Z] Finished clobber step (success) 12:01:56 INFO - [mozharness: 2017-04-16 19:01:56.704156Z] Running read-buildbot-config step. 12:01:56 INFO - Running pre-action listener: _resource_record_pre_action 12:01:56 INFO - Running main action method: read_buildbot_config 12:01:56 INFO - Using buildbot properties: 12:01:56 INFO - { 12:01:56 INFO - "project": "", 12:01:56 INFO - "product": "firefox", 12:01:56 INFO - "script_repo_revision": "production", 12:01:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:01:56 INFO - "repository": "", 12:01:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:01:56 INFO - "buildid": "20170416110106", 12:01:56 INFO - "pgo_build": "False", 12:01:56 INFO - "basedir": "/builds/slave/test", 12:01:56 INFO - "buildnumber": 11, 12:01:56 INFO - "slavename": "t-yosemite-r7-0367", 12:01:56 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:01:56 INFO - "platform": "yosemite_r7", 12:01:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:01:56 INFO - "branch": "mozilla-central", 12:01:56 INFO - "repo_path": "mozilla-central", 12:01:56 INFO - "revision": "c697e756f738ce37abc56f31bfbc48f55625d617", 12:01:56 INFO - "stage_platform": "macosx64", 12:01:56 INFO - "builduid": "3e5c7d46a6144eb38fa34dbfbbc58e6d", 12:01:56 INFO - "slavebuilddir": "test" 12:01:56 INFO - } 12:01:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 12:01:56 INFO - Running post-action listener: _resource_record_post_action 12:01:56 INFO - [mozharness: 2017-04-16 19:01:56.707424Z] Finished read-buildbot-config step (success) 12:01:56 INFO - [mozharness: 2017-04-16 19:01:56.707561Z] Running download-and-extract step. 12:01:56 INFO - Running pre-action listener: _resource_record_pre_action 12:01:56 INFO - Running main action method: download_and_extract 12:01:56 INFO - mkdir: /builds/slave/test/build/tests 12:01:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:01:56 INFO - trying https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:56 INFO - Downloading https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:01:58 INFO - Downloaded 1325 bytes. 12:01:58 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:01:58 INFO - Using the following test package requirements: 12:01:58 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:01:58 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:01:58 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:01:58 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:01:58 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'jsshell-mac.zip'], 12:01:58 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:01:58 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:01:58 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:01:58 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:01:58 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:01:58 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:01:58 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:01:58 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:01:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:01:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:01:58 INFO - Fetch https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:01:59 INFO - Expected file size: 52664064 12:01:59 INFO - Obtained file size: 52664064 12:02:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:02:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:02:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:02:15 INFO - Fetch https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:02:17 INFO - Expected file size: 52664064 12:02:17 INFO - Obtained file size: 52664064 12:02:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:02:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:02:32 INFO - Fetch https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:02:34 INFO - Expected file size: 14408802 12:02:34 INFO - Obtained file size: 14408802 12:02:34 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:34 INFO - trying https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:02:34 INFO - Downloading https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:02:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:02:36 INFO - Downloaded 71444644 bytes. 12:02:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:02:36 INFO - mkdir: /builds/slave/test/properties 12:02:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:02:36 INFO - Writing to file /builds/slave/test/properties/build_url 12:02:36 INFO - Contents: 12:02:36 INFO - build_url:https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:02:37 INFO - Running post-action listener: _resource_record_post_action 12:02:37 INFO - Running post-action listener: set_extra_try_arguments 12:02:37 INFO - [mozharness: 2017-04-16 19:02:37.333560Z] Finished download-and-extract step (success) 12:02:37 INFO - [mozharness: 2017-04-16 19:02:37.333715Z] Running populate-webroot step. 12:02:37 INFO - Running pre-action listener: _resource_record_pre_action 12:02:37 INFO - Running main action method: populate_webroot 12:02:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:02:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:02:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:02:37 INFO - '--tppagecycles', 12:02:37 INFO - '7'], 12:02:37 INFO - 'tests': ['tsvgm']}}, 12:02:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:02:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:02:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:02:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:02:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:02:37 INFO - 'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:02:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:02:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:02:37 INFO - 'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['damp', 'tps']}, 12:02:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:02:37 INFO - 'tests': ['damp', 'tps']}, 12:02:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['dromaeo_dom']}, 12:02:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:02:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:02:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:02:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['a11yr', 12:02:37 INFO - 'ts_paint', 12:02:37 INFO - 'tpaint', 12:02:37 INFO - 'sessionrestore', 12:02:37 INFO - 'sessionrestore_no_auto_restore', 12:02:37 INFO - 'tabpaint']}, 12:02:37 INFO - 'other-e10s': {'tests': ['a11yr', 12:02:37 INFO - 'ts_paint', 12:02:37 INFO - 'tpaint', 12:02:37 INFO - 'sessionrestore', 12:02:37 INFO - 'sessionrestore_no_auto_restore', 12:02:37 INFO - 'tabpaint']}, 12:02:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:02:37 INFO - 'ts_paint', 12:02:37 INFO - 'tpaint', 12:02:37 INFO - 'sessionrestore', 12:02:37 INFO - 'sessionrestore_no_auto_restore', 12:02:37 INFO - 'tabpaint']}, 12:02:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:02:37 INFO - 'ts_paint', 12:02:37 INFO - 'tpaint', 12:02:37 INFO - 'sessionrestore', 12:02:37 INFO - 'sessionrestore_no_auto_restore', 12:02:37 INFO - 'tabpaint']}, 12:02:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['a11yr', 12:02:37 INFO - 'ts_paint', 12:02:37 INFO - 'tpaint', 12:02:37 INFO - 'sessionrestore', 12:02:37 INFO - 'sessionrestore_no_auto_restore', 12:02:37 INFO - 'tabpaint']}, 12:02:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['a11yr', 12:02:37 INFO - 'ts_paint', 12:02:37 INFO - 'tpaint', 12:02:37 INFO - 'sessionrestore', 12:02:37 INFO - 'sessionrestore_no_auto_restore', 12:02:37 INFO - 'tabpaint']}, 12:02:37 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:02:37 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:02:37 INFO - 'bloom_basic_ref']}, 12:02:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['tsvgx', 12:02:37 INFO - 'tsvgr_opacity', 12:02:37 INFO - 'tart', 12:02:37 INFO - 'tscrollx', 12:02:37 INFO - 'cart', 12:02:37 INFO - 'tsvg_static']}, 12:02:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:02:37 INFO - 'tsvgr_opacity', 12:02:37 INFO - 'tart', 12:02:37 INFO - 'tscrollx', 12:02:37 INFO - 'cart', 12:02:37 INFO - 'tsvg_static']}, 12:02:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:02:37 INFO - 'talos_options': ['--disable-e10s'], 12:02:37 INFO - 'tests': ['tp5o']}, 12:02:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:02:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:02:37 INFO - 'talos_options': ['--disable-e10s', 12:02:37 INFO - '--xperf_path', 12:02:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:37 INFO - 'tests': ['tp5n']}, 12:02:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:02:37 INFO - 'talos_options': ['--xperf_path', 12:02:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:02:37 INFO - 'tests': ['tp5n']}}, 12:02:37 INFO - 'talos.zip': {'path': '', 12:02:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:02:37 INFO - Running post-action listener: _resource_record_post_action 12:02:37 INFO - [mozharness: 2017-04-16 19:02:37.352336Z] Finished populate-webroot step (success) 12:02:37 INFO - [mozharness: 2017-04-16 19:02:37.352479Z] Running create-virtualenv step. 12:02:37 INFO - Running pre-action listener: _resource_record_pre_action 12:02:37 INFO - Running main action method: create_virtualenv 12:02:37 INFO - Creating virtualenv /builds/slave/test/build/venv 12:02:37 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 12:02:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:02:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:02:37 INFO - Using real prefix '/tools/python27' 12:02:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:02:38 INFO - Installing distribute.............................................................................................................................................................................................done. 12:02:41 INFO - Installing pip.................done. 12:02:41 INFO - Return code: 0 12:02:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:02:41 INFO - Reading from file tmpfile_stdout 12:02:41 INFO - Output received: 12:02:41 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:02:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:41 INFO - 'HOME': '/Users/cltbld', 12:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:41 INFO - 'LOGNAME': 'cltbld', 12:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:41 INFO - 'MOZ_NO_REMOTE': '1', 12:02:41 INFO - 'NO_EM_RESTART': '1', 12:02:41 INFO - 'PAGER': '/bin/cat', 12:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:41 INFO - 'PWD': '/builds/slave/test', 12:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:41 INFO - 'SHELL': '/bin/bash', 12:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:02:41 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:41 INFO - 'USER': 'cltbld', 12:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:41 INFO - 'XPC_FLAGS': '0x0', 12:02:41 INFO - 'XPC_SERVICE_NAME': '0', 12:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:41 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 12:02:41 INFO - Cleaning up... 12:02:41 INFO - Return code: 0 12:02:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:41 INFO - 'HOME': '/Users/cltbld', 12:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:41 INFO - 'LOGNAME': 'cltbld', 12:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:41 INFO - 'MOZ_NO_REMOTE': '1', 12:02:41 INFO - 'NO_EM_RESTART': '1', 12:02:41 INFO - 'PAGER': '/bin/cat', 12:02:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:41 INFO - 'PWD': '/builds/slave/test', 12:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:41 INFO - 'SHELL': '/bin/bash', 12:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:02:41 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:41 INFO - 'USER': 'cltbld', 12:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:41 INFO - 'XPC_FLAGS': '0x0', 12:02:41 INFO - 'XPC_SERVICE_NAME': '0', 12:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:41 INFO - Downloading/unpacking psutil>=3.1.1 12:02:41 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 12:02:41 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 12:02:41 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 12:02:41 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 12:02:41 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 12:02:41 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 12:02:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:02:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:45 INFO - Installing collected packages: psutil 12:02:45 INFO - Running setup.py install for psutil 12:02:45 INFO - building 'psutil._psutil_osx' extension 12:02:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:02:46 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 12:02:46 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 12:02:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:02:46 INFO - building 'psutil._psutil_posix' extension 12:02:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:02:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:02:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:02:46 INFO - ^ 12:02:46 INFO - 1 warning generated. 12:02:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:02:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:02:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:02:46 INFO - Successfully installed psutil 12:02:46 INFO - Cleaning up... 12:02:46 INFO - Return code: 0 12:02:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:02:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:02:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:02:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:02:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:46 INFO - 'HOME': '/Users/cltbld', 12:02:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:46 INFO - 'LOGNAME': 'cltbld', 12:02:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:46 INFO - 'MOZ_NO_REMOTE': '1', 12:02:46 INFO - 'NO_EM_RESTART': '1', 12:02:46 INFO - 'PAGER': '/bin/cat', 12:02:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:46 INFO - 'PWD': '/builds/slave/test', 12:02:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:46 INFO - 'SHELL': '/bin/bash', 12:02:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:02:46 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:02:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:46 INFO - 'USER': 'cltbld', 12:02:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:46 INFO - 'XPC_FLAGS': '0x0', 12:02:46 INFO - 'XPC_SERVICE_NAME': '0', 12:02:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:02:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:02:49 INFO - Installing collected packages: mozsystemmonitor 12:02:49 INFO - Running setup.py install for mozsystemmonitor 12:02:49 INFO - Successfully installed mozsystemmonitor 12:02:49 INFO - Cleaning up... 12:02:50 INFO - Return code: 0 12:02:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:02:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:02:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:50 INFO - 'HOME': '/Users/cltbld', 12:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:50 INFO - 'LOGNAME': 'cltbld', 12:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:50 INFO - 'MOZ_NO_REMOTE': '1', 12:02:50 INFO - 'NO_EM_RESTART': '1', 12:02:50 INFO - 'PAGER': '/bin/cat', 12:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:50 INFO - 'PWD': '/builds/slave/test', 12:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:50 INFO - 'SHELL': '/bin/bash', 12:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:02:50 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:50 INFO - 'USER': 'cltbld', 12:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:50 INFO - 'XPC_FLAGS': '0x0', 12:02:50 INFO - 'XPC_SERVICE_NAME': '0', 12:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:50 INFO - Downloading/unpacking jsonschema==2.5.1 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:02:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:02:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:02:53 INFO - Downloading functools32-3.2.3-2.tar.gz 12:02:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:02:53 INFO - warning: no files found matching '*.txt' 12:02:53 INFO - no previously-included directories found matching 'build' 12:02:53 INFO - no previously-included directories found matching 'dist' 12:02:53 INFO - no previously-included directories found matching '.git*' 12:02:53 INFO - Installing collected packages: jsonschema, functools32 12:02:53 INFO - Running setup.py install for functools32 12:02:53 INFO - warning: no files found matching '*.txt' 12:02:53 INFO - no previously-included directories found matching 'build' 12:02:53 INFO - no previously-included directories found matching 'dist' 12:02:53 INFO - no previously-included directories found matching '.git*' 12:02:53 INFO - Successfully installed jsonschema functools32 12:02:53 INFO - Cleaning up... 12:02:53 INFO - Return code: 0 12:02:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02: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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:02: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:02: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 functools32==3.2.3-2 12:02:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:02:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:53 INFO - 'HOME': '/Users/cltbld', 12:02:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:53 INFO - 'LOGNAME': 'cltbld', 12:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:53 INFO - 'MOZ_NO_REMOTE': '1', 12:02:53 INFO - 'NO_EM_RESTART': '1', 12:02:53 INFO - 'PAGER': '/bin/cat', 12:02:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:53 INFO - 'PWD': '/builds/slave/test', 12:02:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:53 INFO - 'SHELL': '/bin/bash', 12:02:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:02:53 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:02:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:53 INFO - 'USER': 'cltbld', 12:02:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:53 INFO - 'XPC_FLAGS': '0x0', 12:02:53 INFO - 'XPC_SERVICE_NAME': '0', 12:02:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:02:54 INFO - Cleaning up... 12:02:54 INFO - Return code: 0 12:02:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:02:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:02:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:54 INFO - 'HOME': '/Users/cltbld', 12:02:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:54 INFO - 'LOGNAME': 'cltbld', 12:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:54 INFO - 'MOZ_NO_REMOTE': '1', 12:02:54 INFO - 'NO_EM_RESTART': '1', 12:02:54 INFO - 'PAGER': '/bin/cat', 12:02:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:54 INFO - 'PWD': '/builds/slave/test', 12:02:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:54 INFO - 'SHELL': '/bin/bash', 12:02:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:02:54 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:02:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:54 INFO - 'USER': 'cltbld', 12:02:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:54 INFO - 'XPC_FLAGS': '0x0', 12:02:54 INFO - 'XPC_SERVICE_NAME': '0', 12:02:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:54 INFO - Downloading/unpacking blobuploader==1.2.4 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:56 INFO - Downloading blobuploader-1.2.4.tar.gz 12:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:02:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:02:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02: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 12:02:58 INFO - Downloading docopt-0.6.1.tar.gz 12:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:02:58 INFO - Installing collected packages: blobuploader, requests, docopt 12:02:58 INFO - Running setup.py install for blobuploader 12:02:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:02:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:02:58 INFO - Running setup.py install for requests 12:02:58 INFO - Running setup.py install for docopt 12:02:58 INFO - Successfully installed blobuploader requests docopt 12:02:58 INFO - Cleaning up... 12:02:59 INFO - Return code: 0 12:02:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:02:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:02:59 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 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:02:59 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 12:02:59 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 12:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:02:59 INFO - 'HOME': '/Users/cltbld', 12:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:02:59 INFO - 'LOGNAME': 'cltbld', 12:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:02:59 INFO - 'MOZ_NO_REMOTE': '1', 12:02:59 INFO - 'NO_EM_RESTART': '1', 12:02:59 INFO - 'PAGER': '/bin/cat', 12:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:02:59 INFO - 'PWD': '/builds/slave/test', 12:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:02:59 INFO - 'SHELL': '/bin/bash', 12:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:02:59 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:02:59 INFO - 'USER': 'cltbld', 12:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:02:59 INFO - 'XPC_FLAGS': '0x0', 12:02:59 INFO - 'XPC_SERVICE_NAME': '0', 12:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-gSB9wm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-yPszHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-w9IwqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-cDmYfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-VAq5yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-JFpbNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-vkQ7ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:02:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:02:59 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-Zjl6M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-JJusoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ZuRIU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-uSDkrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-9T7byr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-DNW7_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-IrZA8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-x9u6a4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-RRNiPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:00 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-fOjdPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:03:01 INFO - Running setup.py install for manifestparser 12:03:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:03:01 INFO - Running setup.py install for mozcrash 12:03:01 INFO - Running setup.py install for mozdebug 12:03:01 INFO - Running setup.py install for mozdevice 12:03:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:03:01 INFO - Running setup.py install for mozfile 12:03:01 INFO - Running setup.py install for mozhttpd 12:03:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:03:01 INFO - Running setup.py install for mozinfo 12:03:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Running setup.py install for mozInstall 12:03:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Running setup.py install for mozleak 12:03:02 INFO - Running setup.py install for mozlog 12:03:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Running setup.py install for moznetwork 12:03:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Running setup.py install for mozprocess 12:03:02 INFO - Running setup.py install for mozprofile 12:03:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:03:02 INFO - Running setup.py install for mozrunner 12:03:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:03:03 INFO - Running setup.py install for mozscreenshot 12:03:03 INFO - Running setup.py install for moztest 12:03:03 INFO - Running setup.py install for mozversion 12:03:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:03:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:03:03 INFO - Cleaning up... 12:03:03 INFO - Return code: 0 12:03:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:03 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:03 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:03 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 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:03:03 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 12:03:03 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 12:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:03 INFO - 'HOME': '/Users/cltbld', 12:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:03 INFO - 'LOGNAME': 'cltbld', 12:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:03 INFO - 'MOZ_NO_REMOTE': '1', 12:03:03 INFO - 'NO_EM_RESTART': '1', 12:03:03 INFO - 'PAGER': '/bin/cat', 12:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:03 INFO - 'PWD': '/builds/slave/test', 12:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:03 INFO - 'SHELL': '/bin/bash', 12:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:03:03 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:03 INFO - 'USER': 'cltbld', 12:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:03 INFO - 'XPC_FLAGS': '0x0', 12:03:03 INFO - 'XPC_SERVICE_NAME': '0', 12:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:03 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-y_px5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:03 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)) 12:03:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:03 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-zLedyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-c3fVpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:04 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-A47LIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-aOzTv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:04 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-h3KWSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:04 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-muLSu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:04 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-tEa5SK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:04 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-yJvvpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:04 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-1bJ37i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 12:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:04 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-gYxvXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:05 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)) 12:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:05 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-ade9BL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:05 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-dcpAfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:05 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)) 12:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:05 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-K_iTUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 12:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:05 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-76ACPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:05 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)) 12:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:05 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-O2eqQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 12:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:05 INFO - Running setup.py (path:/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/pip-o4gvvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:05 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)) 12:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:05 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)) 12:03:05 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:05 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:03:05 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 12:03:05 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 12:03:05 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 12:03:05 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 12:03:05 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 12:03:05 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 12:03:08 INFO - Downloading blessings-1.6.tar.gz 12:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:03:08 INFO - Installing collected packages: blessings 12:03:08 INFO - Running setup.py install for blessings 12:03:08 INFO - Successfully installed blessings 12:03:08 INFO - Cleaning up... 12:03:08 INFO - Return code: 0 12:03:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:08 INFO - Reading from file tmpfile_stdout 12:03:08 INFO - Current package versions: 12:03:08 INFO - blessings == 1.6 12:03:08 INFO - blobuploader == 1.2.4 12:03:08 INFO - docopt == 0.6.1 12:03:08 INFO - functools32 == 3.2.3-2 12:03:08 INFO - jsonschema == 2.5.1 12:03:08 INFO - manifestparser == 1.1 12:03:08 INFO - mozInstall == 1.12 12:03:08 INFO - mozcrash == 1.0 12:03:08 INFO - mozdebug == 0.1 12:03:08 INFO - mozdevice == 0.50 12:03:08 INFO - mozfile == 1.2 12:03:08 INFO - mozhttpd == 0.7 12:03:08 INFO - mozinfo == 0.9 12:03:08 INFO - mozleak == 0.1 12:03:08 INFO - mozlog == 3.4 12:03:08 INFO - moznetwork == 0.27 12:03:08 INFO - mozprocess == 0.25 12:03:08 INFO - mozprofile == 0.28 12:03:08 INFO - mozrunner == 6.13 12:03:08 INFO - mozscreenshot == 0.1 12:03:08 INFO - mozsystemmonitor == 0.3 12:03:08 INFO - moztest == 0.8 12:03:08 INFO - mozversion == 1.4 12:03:08 INFO - psutil == 3.1.1 12:03:08 INFO - requests == 1.2.3 12:03:08 INFO - wsgiref == 0.1.2 12:03:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:08 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:08 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:08 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 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:03:08 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 12:03:08 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 12:03:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:03:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:08 INFO - 'HOME': '/Users/cltbld', 12:03:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:08 INFO - 'LOGNAME': 'cltbld', 12:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:08 INFO - 'MOZ_NO_REMOTE': '1', 12:03:08 INFO - 'NO_EM_RESTART': '1', 12:03:08 INFO - 'PAGER': '/bin/cat', 12:03:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:08 INFO - 'PWD': '/builds/slave/test', 12:03:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:08 INFO - 'SHELL': '/bin/bash', 12:03:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:03:08 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:03:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:08 INFO - 'USER': 'cltbld', 12:03:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:08 INFO - 'XPC_FLAGS': '0x0', 12:03:08 INFO - 'XPC_SERVICE_NAME': '0', 12:03:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 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)) 12:03:09 INFO - Cleaning up... 12:03:09 INFO - Return code: 0 12:03:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:03:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:03:09 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 0x10f22bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f22e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fab48563c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, '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.LmxF1PXh6V/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.EokiCmZl1c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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 12:03:09 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 12:03:09 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 12:03:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:03:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:09 INFO - 'HOME': '/Users/cltbld', 12:03:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:09 INFO - 'LOGNAME': 'cltbld', 12:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:09 INFO - 'MOZ_NO_REMOTE': '1', 12:03:09 INFO - 'NO_EM_RESTART': '1', 12:03:09 INFO - 'PAGER': '/bin/cat', 12:03:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:09 INFO - 'PWD': '/builds/slave/test', 12:03:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:09 INFO - 'SHELL': '/bin/bash', 12:03:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:03:09 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:03:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:09 INFO - 'USER': 'cltbld', 12:03:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:09 INFO - 'XPC_FLAGS': '0x0', 12:03:09 INFO - 'XPC_SERVICE_NAME': '0', 12:03:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:03:09 INFO - Cleaning up... 12:03:09 INFO - Return code: 0 12:03:09 INFO - Running post-action listener: _resource_record_post_action 12:03:09 INFO - Running post-action listener: _start_resource_monitoring 12:03:09 INFO - Starting resource monitoring. 12:03:09 INFO - [mozharness: 2017-04-16 19:03:09.547511Z] Finished create-virtualenv step (success) 12:03:09 INFO - [mozharness: 2017-04-16 19:03:09.548473Z] Running install step. 12:03:09 INFO - Running pre-action listener: _resource_record_pre_action 12:03:09 INFO - Running main action method: install 12:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:09 INFO - Reading from file tmpfile_stdout 12:03:09 INFO - Detecting whether we're running mozinstall >=1.0... 12:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:03:09 INFO - Reading from file tmpfile_stdout 12:03:09 INFO - Output received: 12:03:09 INFO - Usage: mozinstall [options] installer 12:03:09 INFO - Options: 12:03:09 INFO - -h, --help show this help message and exit 12:03:09 INFO - -d DEST, --destination=DEST 12:03:09 INFO - Directory to install application into. [default: 12:03:09 INFO - "/builds/slave/test"] 12:03:09 INFO - --app=APP Application being installed. [default: firefox] 12:03:09 INFO - mkdir: /builds/slave/test/build/application 12:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:03:30 INFO - Reading from file tmpfile_stdout 12:03:30 INFO - Output received: 12:03:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:03:30 INFO - Running post-action listener: _resource_record_post_action 12:03:30 INFO - [mozharness: 2017-04-16 19:03:30.022367Z] Finished install step (success) 12:03:30 INFO - [mozharness: 2017-04-16 19:03:30.022475Z] Running run-tests step. 12:03:30 INFO - Running pre-action listener: _resource_record_pre_action 12:03:30 INFO - Running main action method: run_tests 12:03:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:03:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:03:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:03:30 INFO - 2017-04-16 12:03:30.037 screenresolution[1636:10799] starting screenresolution argv=screenresolution get 12:03:30 INFO - 2017-04-16 12:03:30.039 screenresolution[1636:10799] Display 0: 1600x1200x32@60 12:03:30 INFO - 2017-04-16 12:03:30.045 screenresolution[1637:10802] starting screenresolution argv=screenresolution list 12:03:30 INFO - Available Modes on Display 0 12:03:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:03:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:03:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:03:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:03:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:03:30 INFO - Intel Iris: 12:03:30 INFO - Chipset Model: Intel Iris 12:03:30 INFO - Type: GPU 12:03:30 INFO - Bus: Built-In 12:03:30 INFO - VRAM (Dynamic, Max): 1536 MB 12:03:30 INFO - Vendor: Intel (0x8086) 12:03:30 INFO - Device ID: 0x0a2e 12:03:30 INFO - Revision ID: 0x0009 12:03:30 INFO - Displays: 12:03:30 INFO - Display: 12:03:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:03:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:03:30 INFO - Display Serial Number: 1600x1200 60 12:03:30 INFO - Main Display: Yes 12:03:30 INFO - Mirror: Off 12:03:30 INFO - Online: Yes 12:03:30 INFO - Rotation: Supported 12:03:30 INFO - Return code: 0 12:03:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:03:30 INFO - Python 2.7.3 12:03:30 INFO - Return code: 0 12:03:30 INFO - grabbing minidump binary from tooltool 12:03: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f009d30>, '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 0x10ef2fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10effebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:03:30 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 12:03:30 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 12:03:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:03:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:03:30 INFO - Return code: 0 12:03:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:03:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:03:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:03:30 INFO - ENV: RUST_BACKTRACE is now 1 12:03:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:03:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:03:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--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 12:03:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0367 --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 12:03:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmxF1PXh6V/Render', 12:03:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:30 INFO - 'HOME': '/Users/cltbld', 12:03:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:30 INFO - 'LOGNAME': 'cltbld', 12:03:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:03:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:30 INFO - 'MOZ_NO_REMOTE': '1', 12:03:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:03:30 INFO - 'NO_EM_RESTART': '1', 12:03:30 INFO - 'PAGER': '/bin/cat', 12:03:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:30 INFO - 'PWD': '/builds/slave/test', 12:03:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:03:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:30 INFO - 'RUST_BACKTRACE': '1', 12:03:30 INFO - 'SHELL': '/bin/bash', 12:03:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EokiCmZl1c/Listeners', 12:03:30 INFO - 'TMPDIR': '/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/', 12:03:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:30 INFO - 'USER': 'cltbld', 12:03:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:30 INFO - 'XPC_FLAGS': '0x0', 12:03:30 INFO - 'XPC_SERVICE_NAME': '0', 12:03:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0367', '--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 12:03:30 INFO - mozversion application_buildid: 20170416110106 12:03:30 INFO - mozversion application_changeset: c697e756f738ce37abc56f31bfbc48f55625d617 12:03:30 INFO - mozversion application_display_name: Nightly 12:03:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:03:30 INFO - mozversion application_name: Firefox 12:03:30 INFO - mozversion application_remotingname: firefox 12:03:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:03:30 INFO - mozversion application_vendor: Mozilla 12:03:30 INFO - mozversion application_version: 55.0a1 12:03:30 INFO - mozversion platform_buildid: 20170416110106 12:03:30 INFO - mozversion platform_changeset: c697e756f738ce37abc56f31bfbc48f55625d617 12:03:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:03:30 INFO - mozversion platform_version: 55.0a1 12:03:30 INFO - using testdate: 1492369410 12:03:30 INFO - actual date: 1492369410 12:03:30 INFO - starting webserver on 'localhost:49236' 12:03:30 INFO - SUITE-START | Running 2 tests 12:03:30 INFO - TEST-START | dromaeo_css 12:03:30 INFO - operating with platform_type : mac_ 12:03:30 INFO - Initialising browser for dromaeo_css test... 12:03:30 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpmwRyb9/profile http://localhost:49236/getInfo.html) 12:03:33 INFO - PID 1647 | __metrics Screen width/height:1600/1200 12:03:33 INFO - PID 1647 | colorDepth:24 12:03:33 INFO - PID 1647 | Browser inner width/height: 1024/689 12:03:33 INFO - PID 1647 | __metrics 12:03:33 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:03:33 INFO - PID 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:03:34 INFO - TEST-INFO | 1647: exit 0 12:03:34 INFO - Browser initialized. 12:03:34 INFO - Running cycle 1/1 for dromaeo_css test... 12:03:34 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpmwRyb9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:03:46 INFO - PID 1650 | RSS: Main: 239992832 12:03:46 INFO - PID 1650 | 12:06:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 12:06:54 INFO - PID 1650 | RSS: Main: 591462400 12:06:54 INFO - PID 1650 | 12:08:36 INFO - PID 1650 | ************************* 12:08:36 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:08:36 INFO - PID 1650 | 12:08:36 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:08:36 INFO - PID 1650 | Full stack: 12:08:36 INFO - PID 1650 | ************************* 12:08:36 INFO - PID 1650 | ************************* 12:08:36 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:08:36 INFO - PID 1650 | 12:08:36 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:08:36 INFO - PID 1650 | Full stack: 12:08:36 INFO - PID 1650 | ************************* 12:08:36 INFO - PID 1650 | ************************* 12:08:36 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:08:36 INFO - PID 1650 | 12:08:36 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:08:36 INFO - PID 1650 | Full stack: 12:08:36 INFO - PID 1650 | ************************* 12:08:36 INFO - PID 1650 | ************************* 12:08:36 INFO - PID 1650 | A coding exception was thrown and uncaught in a Task. 12:08:36 INFO - PID 1650 | 12:08:36 ERROR - PID 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:08:36 INFO - PID 1650 | Full stack: 12:08:36 INFO - PID 1650 | ************************* 12:09:57 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 12:09:58 INFO - PID 1650 | RSS: Main: 371105792 12:09:58 INFO - PID 1650 | 12:13:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 12:13:01 INFO - PID 1650 | RSS: Main: 321482752 12:13:01 INFO - PID 1650 | 12:16:03 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 12:16:04 INFO - PID 1650 | RSS: Main: 359903232 12:16:04 INFO - PID 1650 | 12:19:07 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 12:19:08 INFO - PID 1650 | RSS: Main: 327749632 12:19:08 INFO - PID 1650 | 12:22:10 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 12:22:11 INFO - PID 1650 | RSS: Main: 374915072 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | __start_tp_report 12:22:11 INFO - PID 1650 | _x_x_mozilla_page_load 12:22:11 INFO - PID 1650 | _x_x_mozilla_page_load_details 12:22:11 INFO - PID 1650 | |i|pagename|runs| 12:22:11 INFO - PID 1650 | |0;dojo.html;3385.221399078212;3446.052335607709;3454.533637958875;3493.9825300873486;3529.3470707919023;5798.942010579893;6018.488428483573;6071.848203794896;6086;6160.137580738695;5911.290645122581;5934.88130237395;5951.137085122655;5952.58331916766;5962.761489540424;5167.276581278624;5183.403908550527;5197.168453047513;5271.683698978054;5281.656692315007;5333.040052790496;5334.413214546396;5342.091844386464;5343.305370301855;5351.678899266037;3889.358255887775;5327.174287985349;5328.733563321838;5333.093374126408;5341.03861304965;5275.155975043975;5276.894462110737;5280.102382594968;5281.735913204325;5286.180642000477;5145.434002259748;5268.183431568074;5268.552173065255;5274.129768588178;5286.365636123652;6150.138980542728;6201.6899155042065;6206.627602343829;6214.347493513206;6234.0337247726675;6235.719392627343;6404.551681382305;6413.903791443132;6431.067495213215;6436.742530298782;6323.14637523933;6340.556161068727;6348.301686814447;6354.3010268870385;6354.809355719336;6041.154238406627;6079.483243924272;6101.450869421729;6173.32093469718;6254.21822272216;6642.900356494656;6654.467642588581;6658.9001164982565;6672.466202703772;6686.4985126115735;6353.078803573456;6361.045843123568;6375.10748495211;6376.171097757355;6383.968080159662;6258.092576576371;6292.182016337847;6310.242770867525;6357.364263573606;6507.9023814642815;6429.871402571923;6433.324500927335;6440.581362211441;6452.9677351612945;6459.386358295955;6442.549021568445;6453.870922581616;6458.06358078076;6459.903101453482;6461.515386345949;5108.642395032386;5149.613778966593;5178.145605974972;5183.248428977777;5252.080885844962;4671.976640116778;4676.672632915731;4691.859244222679;4709.717416954925;4713.410823647044;6086.452219300196;6102;6161.753529858755;6165.475934545524;6173.506119510428;5672.489475947102;6127.816165514952;6332.715027823759;6543.770968016097;6577.736890524421;5983.850403739942;5994.820155395345;6007.549433792396;6010.849728756729;6017.759289628366;5621.15682647598;5638.210650508932;5639.097744360883;5642.4075472075665;5651.350094739158;6336.891044067178;6383.648899310582;6387.297397286295;6391.808245752635;6404.135441715402;6354.936450635434;6355.777547785805;6361.141245931925;6372.426481616677;6381.521385895983;6480.611163330215;6593.044008618819;6604.3725846044545;6609.4712423005085;6611.768588099393;6378.234611846423;6391.073294372383;6392.552521323462;6393.6163830170335;6402.231732192166;6272.278687950761;6343.619382837044;6433.228012638513;6442.677866106582;6443.581167224115;6266.342034086355;6322.841928951813;6325.55721099537;6338.651374174465;6339.651319177305;6218;6315.873682526416;6318.6840657965995;6328.272248691459;6348.746050157942;5119.385673719177;6051.667158306335;6164.47601953839;6165.260168779775;6175.96912015455;6134.620279381683;6280.403361680633;6304.18045654062;6305.558610897193;6307.779227727007;6147.586055207238;6155.569110162246;6255.40573645503;6319.652419116809;6336.9049464259 12:22:11 INFO - PID 1650 | |1;ext.html;658.9308122647122;809.4536188073051;1137.9146564007697;1156.768646270745;1158.1371877950928;35734;36213.27573448533;36613.81693091534;36645.26709465809;36680.89957301285;38127.428088578694;38389.616103838955;38439.423408648894;38509.807450962704;38560.228795424075;8491.448055876379;8621.267192288631;8624.913750862474;8625.65497380104;8643.827123457528;8399.076101628847;8559.186877246651;8560.785980350478;8561.44350617208;8563;8522.977242730882;8544.957275213616;8575.914240857574;8587.484750914933;8590.098039705834;8520.147985201462;8546.87179692302;8554.914450855475;8558.572071396467;8617.224449799485;8348.039975402846;8423.494590324539;8427.199416055464;8431.620577073985;8442.535660538668;30292.03101798376;36567.4514882277;36685.81657091698;36785.632143678486;36789.44815827765;3584.2831433713363;3621.27574485104;3622.1125824173246;3624.0939765058733;3639.581448133472;29825.956091536693;29911.252218694488;29917.551236731466;30005.549916751264;30020.6470061842;3168.1921110117005;3174.016055022927;3176.587043684306;3176.857041433118;3182.9363412731846;241579;257192;303039;304046;304515.4774226137;38257.61742382596;38856.6114338853;38952.41571376432;46392.768036160276;46711;40939.59060409417;40985;41027.58972410238;41033.589664102976;48604.75697621489;13528.32358382077;13626.523071692443;13718.245496497788;13888.4444622216;13900.652483687787;11472.31166130035;11476.88523114775;11477.311361318347;11481.311121332747;11488.94255528717;10146.695599132037;10363.430011349297;11779.234349767237;11814.763704725858;11942.462589183508;4179.913222562095;4823.975880120577;5121.436641969381;5313.1764576490705;5437.429069947678;1004.1063453526367;1011.726833754882;1038.4963292802984;1047.8113939490966;1058.7300238439302;22948.88525557395;24227.39431514191;24264;24319.392015199763;24419.23824854172;970.8980557041409;1079.611339917629;1108.944552772358;1121.85415895933;1122.405125283601;9603.663871764455;11986.940065299617;12371;12392.318422486669;12393.442295096562;248903;298777;298879;299651;319991;52643;52968.47031529713;52973.735131324094;52973.735131324094;53219.73390133025;1453.273363318341;1486.3682934752471;1498.4380857178558;1498.5279636914338;1527.3356090100842;27619.44761104807;27801.721982779913;28578.28554286076;28664.57003144913;28687.426251474437;36469.27061458809;36489.63510364862;36513.269734604626;36533.817330913174;36563.63436365602;262007.37992620593;262950;263264;263321;265109.6744516265;27751.722482774912;28124.43751125007;28242.87028518836;31321.06036818899;31418.842905785325;40900.386494202015;41718;41730.95672608209;41767.373489397076;41871.7906410466;27426.862865685544;28495.857520712263;29039.27401814887;29612.40775184441;29835;28592.42815143727;28868.566971495864;29362.55956160702;29564.96522621698;29644.04645166384;34635.4804677925;34666.82666586651;34682.653173467945;34733.826330868185;34750.47874281837;28158.71841281561;28178.577321340606;28978.855105724335;29111;30005.460412012908;18156.43011349009;18674.346397876554;21626.372748293325;21739.56520869542;22481.763503006838 12:22:11 INFO - PID 1650 | |2;jquery.html;375.59435809325925;412.39378114172155;419.34582051998905;424.92563801334757;438.62716690812795;19772.70340944887;20709.171633134676;20892.791072089276;21472;21615.67576486354;21842.235521756742;21994.560108797854;22073.33779986603;22519.774802252014;22738.976746046425;5741.339745929219;6255.843603909916;6259.155014073087;6268.091126786613;6270.529710271725;5988.7604495820115;6131.509479241672;6185.4433101020895;6274.466670333027;6292.779752708656;6213.720382582772;6229.719662615171;6242.406971337716;6246.968765156168;6248.062790581426;5974.133750606159;6145.969270153643;6220.066989951498;6228.034654628563;6232.688365581703;5304.655197412156;5444.6188766786345;5521.365043020064;5527.861803454945;5531.474509921592;26435.867820660966;26438.47123057528;26553;26682.665866706782;26832.329191770168;2202.878841663708;2236.575050740354;2271.943201419961;2272.5909336319455;2277.5786479501344;17426.65146697059;17605.911970440065;17926.641467170713;17954.371596994042;18122.184501697324;2075.6886467029917;2157.6332023556033;2186.7703891091523;2187.9781202187933;2189.638709612912;141740.2912985439;142362.57637423597;143039.55834458722;143302;145370.81943770853;11177.888221117848;11683.591074312359;11709.648710538697;11714.769949155216;11911.463984120735;15765.132917689407;15772.369105235663;15798.946137002345;15800.841991580168;15808.68382632341;8429.957850210709;8754;8757.080506546848;8859.557022148867;8903.777405564782;8378.622961966727;8394.45436046655;8414.200650938152;8578.142185781371;8598.742037738877;6026.668533230627;6077.665728384981;7230.891536626955;7324.304191101837;7326.706931722778;2017.3544465771106;2023.8684485508395;2024.2914979756968;2027.2093883385492;2047.805458481442;924.6301479408156;953.5613617735919;957.2150836314156;1001.4742260313394;1014.5992333028413;19165.712514312017;20093.598128037356;20840;20954.89522552408;21015.369538913856;949.1837020162654;993.5280741647659;1024.7950409918046;1026.343140390151;1039.3815679670583;8871.733847984571;10204.795904081877;10740.570377184982;10924.672259832218;11072.3910184939;551553;562729;567993;568105;576600;103744;110606.89393106126;116861;135515.96726049116;142719;1135.8903487183345;1165.609520810521;1214.2775048845922;1224.8775122487857;1226.068188176974;21725.456863577903;22017.313510329222;22134.889325553268;22233.22183723562;22352.882355882466;57806.71096644658;58244.12633810411;58571;59728.40271597228;59792.40207597868;80778.59610701908;82441.58779206306;82490;83534.16465835265;84013.57993209995;7521.172671006076;8082.949216601804;8546.401751877558;8714.607842646968;8861.33539984491;13881.72236555243;13897.722045559234;14187.290635467978;14189.716205676035;14295.785563216352;11496.252743571642;11750.823737644114;11793;11869.821952670543;11880.287182769061;11126.998570128728;11159.507583865583;11223.438828058728;11552.768944621228;11666.008389287006;10030.64892728751;11419.60031398897;11705.297682139098;11856.11079169083;12204.267743935392;8695.956520217358;8739.820124283395;8818.971636523764;8924;9041.50271735061;11150.386728729998;11282.266652667551;12156.331401772633;12430;12430.254184748583 12:22:11 INFO - PID 1650 | |3;mootools.html;1397.9021468497206;1468.6915747692983;1478.5268714011506;1493.4922126477;1494.4769330734237;11215.271007384514;11659.300441973477;11665.475053622586;11666.82499762502;11679.299242045461;11282.153838462107;11363.034142097931;11642.941785291061;11678.883211167908;11687.474063667134;2498.4503409249983;2543.8855251513633;2545;2546.2997675639253;2552.668153140089;2491.327341617762;2494.87525623719;2532.708738495069;2540.3649087728068;2553.4637726077544;2408.8795560222006;2451.472933319336;2526.873656317186;2541.199522150526;2553.042609021617;2414.6860908081835;2479.429731161834;2488.4276616378243;2526.2421273618024;2526.5831137862233;2449.4366295752075;2476.418041760185;2478.5414698280697;2479.776820086183;2494.600863861773;11469.426528673534;11710.590129345432;11710.765784684343;11714.941425292904;11727.765444691058;6408.551401401904;6675.365840245169;6678.6660666966945;6680.5991640501725;6686.765963191265;4015.8795236142964;4241.766702831343;4271;4345.760983145926;4355.542668019875;1217.1844863941092;1231.7721221574038;1247.4698253242427;1289.929053902035;1295.307010749254;15037.248137593077;15476.690466190727;15943.28255228506;16317.265723042492;16439.753403698953;4774.594159496412;4901.975490122598;5078.08594453002;5501.587380946445;5632.436756324417;12282.867868693425;12301.507939682491;12304.938475307566;12315.13794034409;12321.322327272086;5677.545796336283;5863.442972917566;6495.447886929664;6495.837604059841;6508.739650413931;6021.2473440819895;6040.184575082308;6168.753249869955;6180.165677633462;6213.099100630383;4288.378185163134;4372.191144638252;4426.424564806619;4436.0240747035605;4449.644028477714;1068.7435015596357;1252.1673087396962;1269.9924594197846;1271.961841144767;1273.1533530202496;788.8027993001749;792.8929594504783;809.8866158737782;812.9390295727844;818.0796603820702;6032.939670603326;6077.186223058563;6269.905951410732;6360.618362898241;6394;571.2602180176681;583.7489879351834;587.5857520448159;592.7925226170892;597.8355952113117;3024.9261512162866;3444.225049363873;3555.306715190565;3781.1870447853844;3785.981070094632;14582.270886455635;15230.238488075773;15231.314590843438;15256.237188140549;15257.923710381378;14663.853361466248;15154.090754554541;15228.086314820925;15275.236238188047;15286.617834554225;8500.44747091437;8608.09614990417;8612.440191387539;8613.870791938127;8636.438631488933;4769.904601907873;6714.731410743516;6720.798376048726;6920.100386949664;7100.857982840211;12429;12714.936425317814;12741.426635801596;12861.97104231678;12884.48462061507;12683.873161268639;12969.870301296865;13106.410211540313;13147.276899770604;13229.07396482237;8487.36344774132;8580.227779499875;8701.521416322163;9551.665691700757;9631.9036809631;10843;10981.780364392827;10984.560617575207;10986.340819550853;10991.780164396827;8967.372283940062;9144.039875812943;9225.475038651279;9266.768330791785;9383.202427793714;8840.867386989328;8885.733427997171;9010.098990100938;9038.548072596475;9081.954590227007;7793.883091753515;7963.601819908866;8060.428216497727;8069.636866341146;8072.556009419538;5962.2249107615735;5978.581499295007;6060.636361818305;6181.103739957591;6207.441330280297;5962.612430192024;5973.253343332083;6088;6149.954507733606;6304.527160463075 12:22:11 INFO - PID 1650 | |4;prototype.html;366.8275910322149;375.6637809160801;387.87781848717634;389.61233572595273;390.67769090500326;5469.808556700516;5769.624974376663;5821.767129314822;5835.941640583598;5839.503642190413;4288.528261891197;4719.150552900476;5236.8690782730355;5450.182472629108;5450.182472629108;4238.88055972014;4248.83004679814;4249.171411574729;4249.723767955088;4269.658427325814;3537.6992955598803;4236.428082208909;4243.702940794145;4253.255680255958;4253.383259427381;4027.8388864445533;4166.166766646667;4188.518320393163;4196.643285320752;4259.914801703964;4207.579242075799;4218.557051509593;4230.957690423087;4231.1749208904275;4241.957580424187;3125.843707814623;3641.217138315275;3679.3259067124395;3686.9078273043388;3700.870469533553;5205.479452054802;5362.758675859595;5546.55627549795;5548.528375088123;5572.024895643287;4142.565030671796;4931.408231012266;4983.626228032912;4992.40091189056;5000.5249501297685;5484.601426636222;6048.848778780522;6071.785642871443;6083.11794789753;6254.280757712876;3774.8678796241998;3903.8243279052226;4083.5099788025627;4139.834406623731;4184;5851.707414629294;5976.880462390728;6012.669303188323;6223.315435302113;6253.280872699653;4116.300228961084;4197.216183122161;4200.306949353384;4204.894877628084;4205.642520385741;4248.490181178278;4258.233517966796;4260.723053001545;4284.76433796138;4285.335772955197;4653.720226937619;4756.8097276108565;4870.561649451566;4910.263460480885;4924.60603151747;6109;6113.541484388688;6124.785632502841;6138.404014956143;6149.2620885494025;1328.9202647840966;1348.7504811609883;1388.7083712420304;1454.7526920423554;1464.3044553836842;794.3327604811985;953.1850268020881;965.8985806490358;970.4032020307601;977.1108291454884;5545.61180717354;5686.91948529778;5839.766409343579;5839.970800145972;5842;5477.397486276506;5760.798372056958;6285.685715714196;6316.44184105438;6325.272593651789;5436.456354364532;5490.121580547173;5822.184894114827;5825.650460972355;5844.444777746107;3243.772935894512;3291.8643068141455;3305.289362787012;3309.5107201759306;3331.1505566080496;4988.603191106496;6050.759594283161;6460.483161347081;6491.060679478224;6500.577462465019;5543.417941116125;6403.615783053032;6478.117237829121;6499.2850786413455;6512.185976752906;5183.429822719573;6072.089186621954;6429.903551446732;6440.323766004596;6479.481641468671;5437.945620543902;6248.375162483715;6258.217722784652;6265.96867015662;6320.273168585488;5352.357717073976;5707.343655479673;5950.434708702666;6162.383761623981;6277.0541132249145;5394.892102157856;5790.913136302875;6355.364463553793;6482.57863238888;6488.610683359014;3837.1941892201835;4087.4890638670167;4193.903048475762;4200.390943313264;4206.305959516645;6014.458698717136;6259.624422534663;6332.271788744353;6332.398422149889;6353.618782873043;5078.238264260391;5145;6037.245344331958;6071.605345652519;6094.238220222473;5731.8853622928145;6049.818505444844;6061.15143879866;6093.390660934048;6112.786052488142;5158.664686795346;5510.586705996986;5963;5989.940100598938;6012;5612.85967850807;6059.787907423219;6099.9390006099375;6125.877482450415;6127.325994140552;4747.572718455355;5413.83758487246;5693.629914055573;5941.494972927349;5957.017092179742 12:22:11 INFO - PID 1650 | |5;yui.html;712.5724565260841;763.9503432276903;869.4739682492092;873.580681272989;883.2183517586938;13504.932475337622;17861.464156075323;17988.55028624285;18590.256389744394;18611.99999999998;14631.634209144777;17596.944183348976;18234.452966410994;18241.817581824176;18347.724784128248;957.0190554681456;980.9068138526865;988.4365911430488;989.717930389839;989.8515222716578;673.2560520624712;689.7137687859532;695.1276148433709;996.4668902137358;1005.6731562242264;757.9431542634325;759.2786852490157;980.9901900980971;1036.9170466362712;1043.8642976413093;1033.4729288063104;1035.6116456328878;1036.207301414411;1036.6164519127894;1038.4132964874898;769.3191525499917;769.7536788227769;772.8415674786655;773.1109224391938;775.1241097559721;11920.284782913053;12325.753484930341;16240.43158489459;16678.749818752727;17052.744208836753;16721.080340581262;16805.747913781302;17394.956302621882;17621;17694.55763605907;13879.930600347034;13973.860261397358;14173.574792756233;14297.928510357486;14299.428022879176;3411.9658803412144;3432.0733401981574;3453.8100404477746;3457.8098204598737;3465.5841299044278;94491.52754236184;123815.76184238133;123886;125288;126210;11407;13514.391852366667;13615.251161186032;13651.453941842414;13668.384922678504;20072.598548028956;20449.591008179752;20498;24795.5040899181;24964.625530617057;6926.480513961473;7784.182660820646;8014.79963000918;8038.39712021589;8047.114817370085;3867.748596341285;3885.3977633466857;3896.8636097736444;3942.6845852331744;3953.920921581552;4358.869233922987;4483.843065492692;4484.0583477469445;4487.057717879216;4495.2358099122575;915.5651065743718;949.520492151459;960.2605993385055;960.3421656165549;960.6589660670501;608.812815010722;649.8862699027651;680.6766785776716;683.6855046678484;689.2865883810232;9312.627494900127;10349.534270957825;11612.88387116118;11874.762504749857;11965.162438629213;636.6752955992384;648.6108334999058;650.6193876582158;664.259350823828;683.7948615415355;4542.795574199169;5374.70439125844;5455.427180146107;5512.862178445491;5646.85882852932;90408.1918361629;103429;104520;104607.4769626147;106926;69462.65268673624;70533.29466705267;88429.11570884337;89895;92996;64701.67649161724;82229.76655350174;83122.58438707767;83653;84508.57745711356;8799.516026618598;9579.952100239454;10878.673639790819;11203.551857925591;11398.658040258806;42519.14961700687;42631.786841065594;43128.70613881588;43623.34564981464;43858.780706096266;54199.45800542102;54813.1778023322;55470.72264638651;55902;55993.16010259931;14195.148291102567;14397.496087636882;14405.567832965027;14474.276286185437;16547.83452165511;17761.00504009753;18450.630987380446;18674.719879202094;18747.34384296543;18789.436316910516;16981.66036679235;17119.14404279756;17234.482965511055;17366.913165434093;17370.044647544506;17156.571085722957;17267.222974965905;17363.56591085233;17409.738853916948;17550.473485795446;16176.352945882032;16426.83573164253;16691.91654041722;16930.068846213577;17012.829871701124;10682.359058456517;11548.538058477567;11683.591074312359;11777.175597708077;12070.818937715765;12964.675883102998;13535;13608.795868061788;13655.156553865101;13703.040787144804 12:22:11 INFO - PID 1650 | __end_tp_report 12:22:11 INFO - PID 1650 | __start_cc_report 12:22:11 INFO - PID 1650 | _x_x_mozilla_cycle_collect,4273 12:22:11 INFO - PID 1650 | __end_cc_report 12:22:11 INFO - PID 1650 | __startTimestamp1492370531228__endTimestamp 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | ------- Summary: start ------- 12:22:11 INFO - PID 1650 | Number of tests: 6 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:5938.56 Median:6214.35 stddev:683.32 (11.0%) stddev-sans-first:655.40 12:22:11 INFO - PID 1650 | Values: 3385.2 3446.1 3454.5 3494.0 3529.3 5798.9 6018.5 6071.8 6086.0 6160.1 5911.3 5934.9 5951.1 5952.6 5962.8 5167.3 5183.4 5197.2 5271.7 5281.7 5333.0 5334.4 5342.1 5343.3 5351.7 3889.4 5327.2 5328.7 5333.1 5341.0 5275.2 5276.9 5280.1 5281.7 5286.2 5145.4 5268.2 5268.6 5274.1 5286.4 6150.1 6201.7 6206.6 6214.3 6234.0 6235.7 6404.6 6413.9 6431.1 6436.7 6323.1 6340.6 6348.3 6354.3 6354.8 6041.2 6079.5 6101.5 6173.3 6254.2 6642.9 6654.5 6658.9 6672.5 6686.5 6353.1 6361.0 6375.1 6376.2 6384.0 6258.1 6292.2 6310.2 6357.4 6507.9 6429.9 6433.3 6440.6 6453.0 6459.4 6442.5 6453.9 6458.1 6459.9 6461.5 5108.6 5149.6 5178.1 5183.2 5252.1 4672.0 4676.7 4691.9 4709.7 4713.4 6086.5 6102.0 6161.8 6165.5 6173.5 5672.5 6127.8 6332.7 6543.8 6577.7 5983.9 5994.8 6007.5 6010.8 6017.8 5621.2 5638.2 5639.1 5642.4 5651.4 6336.9 6383.6 6387.3 6391.8 6404.1 6354.9 6355.8 6361.1 6372.4 6381.5 6480.6 6593.0 6604.4 6609.5 6611.8 6378.2 6391.1 6392.6 6393.6 6402.2 6272.3 6343.6 6433.2 6442.7 6443.6 6266.3 6322.8 6325.6 6338.7 6339.7 6218.0 6315.9 6318.7 6328.3 6348.7 5119.4 6051.7 6164.5 6165.3 6176.0 6134.6 6280.4 6304.2 6305.6 6307.8 6147.6 6155.6 6255.4 6319.7 6336.9 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:42369.11 Median:17293.52 stddev:73494.70 (425.0%) stddev-sans-first:73634.15 12:22:11 INFO - PID 1650 | Values: 658.9 809.5 1137.9 1156.8 1158.1 35734.0 36213.3 36613.8 36645.3 36680.9 38127.4 38389.6 38439.4 38509.8 38560.2 8491.4 8621.3 8624.9 8625.7 8643.8 8399.1 8559.2 8560.8 8561.4 8563.0 8523.0 8545.0 8575.9 8587.5 8590.1 8520.1 8546.9 8554.9 8558.6 8617.2 8348.0 8423.5 8427.2 8431.6 8442.5 30292.0 36567.5 36685.8 36785.6 36789.4 3584.3 3621.3 3622.1 3624.1 3639.6 29826.0 29911.3 29917.6 30005.5 30020.6 3168.2 3174.0 3176.6 3176.9 3182.9 241579.0 257192.0 303039.0 304046.0 304515.5 38257.6 38856.6 38952.4 46392.8 46711.0 40939.6 40985.0 41027.6 41033.6 48604.8 13528.3 13626.5 13718.2 13888.4 13900.7 11472.3 11476.9 11477.3 11481.3 11488.9 10146.7 10363.4 11779.2 11814.8 11942.5 4179.9 4824.0 5121.4 5313.2 5437.4 1004.1 1011.7 1038.5 1047.8 1058.7 22948.9 24227.4 24264.0 24319.4 24419.2 970.9 1079.6 1108.9 1121.9 1122.4 9603.7 11986.9 12371.0 12392.3 12393.4 248903.0 298777.0 298879.0 299651.0 319991.0 52643.0 52968.5 52973.7 52973.7 53219.7 1453.3 1486.4 1498.4 1498.5 1527.3 27619.4 27801.7 28578.3 28664.6 28687.4 36469.3 36489.6 36513.3 36533.8 36563.6 262007.4 262950.0 263264.0 263321.0 265109.7 27751.7 28124.4 28242.9 31321.1 31418.8 40900.4 41718.0 41731.0 41767.4 41871.8 27426.9 28495.9 29039.3 29612.4 29835.0 28592.4 28868.6 29362.6 29565.0 29644.0 34635.5 34666.8 34682.7 34733.8 34750.5 28158.7 28178.6 28978.9 29111.0 30005.5 18156.4 18674.3 21626.4 21739.6 22481.8 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:35602.68 Median:22184.06 stddev:95222.85 (429.2%) stddev-sans-first:95453.24 12:22:11 INFO - PID 1650 | Values: 375.6 412.4 419.3 424.9 438.6 19772.7 20709.2 20892.8 21472.0 21615.7 21842.2 21994.6 22073.3 22519.8 22739.0 5741.3 6255.8 6259.2 6268.1 6270.5 5988.8 6131.5 6185.4 6274.5 6292.8 6213.7 6229.7 6242.4 6247.0 6248.1 5974.1 6146.0 6220.1 6228.0 6232.7 5304.7 5444.6 5521.4 5527.9 5531.5 26435.9 26438.5 26553.0 26682.7 26832.3 2202.9 2236.6 2271.9 2272.6 2277.6 17426.7 17605.9 17926.6 17954.4 18122.2 2075.7 2157.6 2186.8 2188.0 2189.6 141740.3 142362.6 143039.6 143302.0 145370.8 11177.9 11683.6 11709.6 11714.8 11911.5 15765.1 15772.4 15798.9 15800.8 15808.7 8430.0 8754.0 8757.1 8859.6 8903.8 8378.6 8394.5 8414.2 8578.1 8598.7 6026.7 6077.7 7230.9 7324.3 7326.7 2017.4 2023.9 2024.3 2027.2 2047.8 924.6 953.6 957.2 1001.5 1014.6 19165.7 20093.6 20840.0 20954.9 21015.4 949.2 993.5 1024.8 1026.3 1039.4 8871.7 10204.8 10740.6 10924.7 11072.4 551553.0 562729.0 567993.0 568105.0 576600.0 103744.0 110606.9 116861.0 135516.0 142719.0 1135.9 1165.6 1214.3 1224.9 1226.1 21725.5 22017.3 22134.9 22233.2 22352.9 57806.7 58244.1 58571.0 59728.4 59792.4 80778.6 82441.6 82490.0 83534.2 84013.6 7521.2 8082.9 8546.4 8714.6 8861.3 13881.7 13897.7 14187.3 14189.7 14295.8 11496.3 11750.8 11793.0 11869.8 11880.3 11127.0 11159.5 11223.4 11552.8 11666.0 10030.6 11419.6 11705.3 11856.1 12204.3 8696.0 8739.8 8819.0 8924.0 9041.5 11150.4 11282.3 12156.3 12430.0 12430.3 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:7017.07 Median:3234.58 stddev:4471.36 (138.2%) stddev-sans-first:4463.97 12:22:11 INFO - PID 1650 | Values: 1397.9 1468.7 1478.5 1493.5 1494.5 11215.3 11659.3 11665.5 11666.8 11679.3 11282.2 11363.0 11642.9 11678.9 11687.5 2498.5 2543.9 2545.0 2546.3 2552.7 2491.3 2494.9 2532.7 2540.4 2553.5 2408.9 2451.5 2526.9 2541.2 2553.0 2414.7 2479.4 2488.4 2526.2 2526.6 2449.4 2476.4 2478.5 2479.8 2494.6 11469.4 11710.6 11710.8 11714.9 11727.8 6408.6 6675.4 6678.7 6680.6 6686.8 4015.9 4241.8 4271.0 4345.8 4355.5 1217.2 1231.8 1247.5 1289.9 1295.3 15037.2 15476.7 15943.3 16317.3 16439.8 4774.6 4902.0 5078.1 5501.6 5632.4 12282.9 12301.5 12304.9 12315.1 12321.3 5677.5 5863.4 6495.4 6495.8 6508.7 6021.2 6040.2 6168.8 6180.2 6213.1 4288.4 4372.2 4426.4 4436.0 4449.6 1068.7 1252.2 1270.0 1272.0 1273.2 788.8 792.9 809.9 812.9 818.1 6032.9 6077.2 6269.9 6360.6 6394.0 571.3 583.7 587.6 592.8 597.8 3024.9 3444.2 3555.3 3781.2 3786.0 14582.3 15230.2 15231.3 15256.2 15257.9 14663.9 15154.1 15228.1 15275.2 15286.6 8500.4 8608.1 8612.4 8613.9 8636.4 4769.9 6714.7 6720.8 6920.1 7100.9 12429.0 12714.9 12741.4 12862.0 12884.5 12683.9 12969.9 13106.4 13147.3 13229.1 8487.4 8580.2 8701.5 9551.7 9631.9 10843.0 10981.8 10984.6 10986.3 10991.8 8967.4 9144.0 9225.5 9266.8 9383.2 8840.9 8885.7 9010.1 9038.5 9082.0 7793.9 7963.6 8060.4 8069.6 8072.6 5962.2 5978.6 6060.6 6181.1 6207.4 5962.6 5973.3 6088.0 6150.0 6304.5 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:4895.67 Median:5487.36 stddev:1515.03 (27.6%) stddev-sans-first:1480.65 12:22:11 INFO - PID 1650 | Values: 366.8 375.7 387.9 389.6 390.7 5469.8 5769.6 5821.8 5835.9 5839.5 4288.5 4719.2 5236.9 5450.2 5450.2 4238.9 4248.8 4249.2 4249.7 4269.7 3537.7 4236.4 4243.7 4253.3 4253.4 4027.8 4166.2 4188.5 4196.6 4259.9 4207.6 4218.6 4231.0 4231.2 4242.0 3125.8 3641.2 3679.3 3686.9 3700.9 5205.5 5362.8 5546.6 5548.5 5572.0 4142.6 4931.4 4983.6 4992.4 5000.5 5484.6 6048.8 6071.8 6083.1 6254.3 3774.9 3903.8 4083.5 4139.8 4184.0 5851.7 5976.9 6012.7 6223.3 6253.3 4116.3 4197.2 4200.3 4204.9 4205.6 4248.5 4258.2 4260.7 4284.8 4285.3 4653.7 4756.8 4870.6 4910.3 4924.6 6109.0 6113.5 6124.8 6138.4 6149.3 1328.9 1348.8 1388.7 1454.8 1464.3 794.3 953.2 965.9 970.4 977.1 5545.6 5686.9 5839.8 5840.0 5842.0 5477.4 5760.8 6285.7 6316.4 6325.3 5436.5 5490.1 5822.2 5825.7 5844.4 3243.8 3291.9 3305.3 3309.5 3331.2 4988.6 6050.8 6460.5 6491.1 6500.6 5543.4 6403.6 6478.1 6499.3 6512.2 5183.4 6072.1 6429.9 6440.3 6479.5 5437.9 6248.4 6258.2 6266.0 6320.3 5352.4 5707.3 5950.4 6162.4 6277.1 5394.9 5790.9 6355.4 6482.6 6488.6 3837.2 4087.5 4193.9 4200.4 4206.3 6014.5 6259.6 6332.3 6332.4 6353.6 5078.2 5145.0 6037.2 6071.6 6094.2 5731.9 6049.8 6061.2 6093.4 6112.8 5158.7 5510.6 5963.0 5989.9 6012.0 5612.9 6059.8 6099.9 6125.9 6127.3 4747.6 5413.8 5693.6 5941.5 5957.0 12:22:11 INFO - PID 1650 | 12:22:11 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:21131.88 Median:22646.75 stddev:29388.22 (129.8%) stddev-sans-first:29430.67 12:22:11 INFO - PID 1650 | Values: 712.6 764.0 869.5 873.6 883.2 13504.9 17861.5 17988.6 18590.3 18612.0 14631.6 17596.9 18234.5 18241.8 18347.7 957.0 980.9 988.4 989.7 989.9 673.3 689.7 695.1 996.5 1005.7 757.9 759.3 981.0 1036.9 1043.9 1033.5 1035.6 1036.2 1036.6 1038.4 769.3 769.8 772.8 773.1 775.1 11920.3 12325.8 16240.4 16678.7 17052.7 16721.1 16805.7 17395.0 17621.0 17694.6 13879.9 13973.9 14173.6 14297.9 14299.4 3412.0 3432.1 3453.8 3457.8 3465.6 94491.5 123815.8 123886.0 125288.0 126210.0 11407.0 13514.4 13615.3 13651.5 13668.4 20072.6 20449.6 20498.0 24795.5 24964.6 6926.5 7784.2 8014.8 8038.4 8047.1 3867.7 3885.4 3896.9 3942.7 3953.9 4358.9 4483.8 4484.1 4487.1 4495.2 915.6 949.5 960.3 960.3 960.7 608.8 649.9 680.7 683.7 689.3 9312.6 10349.5 11612.9 11874.8 11965.2 636.7 648.6 650.6 664.3 683.8 4542.8 5374.7 5455.4 5512.9 5646.9 90408.2 103429.0 104520.0 104607.5 106926.0 69462.7 70533.3 88429.1 89895.0 92996.0 64701.7 82229.8 83122.6 83653.0 84508.6 8799.5 9580.0 10878.7 11203.6 11398.7 42519.1 42631.8 43128.7 43623.3 43858.8 54199.5 54813.2 55470.7 55902.0 55993.2 14195.1 14397.5 14405.6 14474.3 16547.8 17761.0 18450.6 18674.7 18747.3 18789.4 16981.7 17119.1 17234.5 17366.9 17370.0 17156.6 17267.2 17363.6 17409.7 17550.5 16176.4 16426.8 16691.9 16930.1 17012.8 10682.4 11548.5 11683.6 11777.2 12070.8 12964.7 13535.0 13608.8 13655.2 13703.0 12:22:11 INFO - PID 1650 | -------- Summary: end -------- 12:22:11 INFO - PID 1650 | 12:22:11 INFO - TEST-INFO | 1650: exit 0 12:22:11 INFO - TEST-OK | dromaeo_css | took 1121318ms 12:22:11 INFO - TEST-START | kraken 12:22:11 INFO - operating with platform_type : mac_ 12:22:11 INFO - Initialising browser for kraken test... 12:22:11 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpZpMm5A/profile http://localhost:49236/getInfo.html) 12:22:14 INFO - PID 1656 | __metrics Screen width/height:1600/1200 12:22:14 INFO - PID 1656 | colorDepth:24 12:22:14 INFO - PID 1656 | Browser inner width/height: 1024/689 12:22:14 INFO - PID 1656 | __metrics 12:22:14 INFO - PID 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:22:14 INFO - PID 1656 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:22:15 INFO - TEST-INFO | 1656: exit 0 12:22:15 INFO - Browser initialized. 12:22:15 INFO - Running cycle 1/1 for kraken test... 12:22:15 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/T/tmpZpMm5A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:28 INFO - PID 1657 | RSS: Main: 241221632 12:22:28 INFO - PID 1657 | 12:22:30 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 12:22:31 INFO - PID 1657 | RSS: Main: 312320000 12:22:31 INFO - PID 1657 | 12:22:34 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 12:22:35 INFO - PID 1657 | RSS: Main: 293535744 12:22:35 INFO - PID 1657 | 12:22:38 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 12:22:39 INFO - PID 1657 | RSS: Main: 389271552 12:22:39 INFO - PID 1657 | 12:22:41 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 12:22:41 INFO - PID 1657 | RSS: Main: 290234368 12:22:41 INFO - PID 1657 | 12:22:43 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:22:44 INFO - PID 1657 | RSS: Main: 292380672 12:22:44 INFO - PID 1657 | 12:22:47 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 12:22:47 INFO - PID 1657 | RSS: Main: 355446784 12:22:47 INFO - PID 1657 | 12:22:50 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 12:22:51 INFO - PID 1657 | RSS: Main: 375234560 12:22:51 INFO - PID 1657 | 12:22:54 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 12:22:54 INFO - PID 1657 | RSS: Main: 383893504 12:22:54 INFO - PID 1657 | 12:22:56 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:22:56 INFO - PID 1657 | RSS: Main: 332902400 12:22:56 INFO - PID 1657 | 12:22:58 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:22:59 INFO - PID 1657 | RSS: Main: 333438976 12:22:59 INFO - PID 1657 | 12:23:01 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:23:02 INFO - PID 1657 | RSS: Main: 335323136 12:23:02 INFO - PID 1657 | 12:23:04 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:23:05 INFO - PID 1657 | RSS: Main: 334516224 12:23:05 INFO - PID 1657 | 12:23:09 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:23:09 INFO - PID 1657 | RSS: Main: 344829952 12:23:09 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 12:23:11 INFO - PID 1657 | RSS: Main: 387551232 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | __start_tp_report 12:23:11 INFO - PID 1657 | _x_x_mozilla_page_load 12:23:11 INFO - PID 1657 | _x_x_mozilla_page_load_details 12:23:11 INFO - PID 1657 | |i|pagename|runs| 12:23:11 INFO - PID 1657 | |0;ai-astar;110;110;110;115;158;164;165;111;110;112 12:23:11 INFO - PID 1657 | |1;audio-beat-detection;140;113;109;152;111;111;109;108;137;107 12:23:11 INFO - PID 1657 | |2;audio-dft;187;182;185;185;188;184;221;192;180;185 12:23:11 INFO - PID 1657 | |3;audio-fft;82;58;61;89;63;81;85;58;80;56 12:23:11 INFO - PID 1657 | |4;audio-oscillator;90;69;87;69;68;70;92;68;68;90 12:23:11 INFO - PID 1657 | |5;imaging-gaussian-blur;87;89;87;87;87;88;87;91;112;88 12:23:11 INFO - PID 1657 | |6;imaging-darkroom;94;94;95;95;94;93;95;95;95;94 12:23:11 INFO - PID 1657 | |7;imaging-desaturate;84;83;83;84;85;84;84;83;84;84 12:23:11 INFO - PID 1657 | |8;json-parse-financial;53;48;48;49;50;48;49;50;50;50 12:23:11 INFO - PID 1657 | |9;json-stringify-tinderbox;54;61;60;66;63;61;57;57;59;58 12:23:11 INFO - PID 1657 | |10;stanford-crypto-aes;83;81;83;83;82;83;81;81;81;72 12:23:11 INFO - PID 1657 | |11;stanford-crypto-ccm;138;138;135;137;132;135;126;145;169;175 12:23:11 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;184;188;187;188;222;228;186;185;185;181 12:23:11 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;66;67;67;67;68;71;93;92;97;89 12:23:11 INFO - PID 1657 | __end_tp_report 12:23:11 INFO - PID 1657 | __start_cc_report 12:23:11 INFO - PID 1657 | _x_x_mozilla_cycle_collect,2241 12:23:11 INFO - PID 1657 | __end_cc_report 12:23:11 INFO - PID 1657 | __startTimestamp1492370591839__endTimestamp 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | ------- Summary: start ------- 12:23:11 INFO - PID 1657 | Number of tests: 14 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:126.50 Median:113.50 stddev:24.84 (21.9%) stddev-sans-first:25.62 12:23:11 INFO - PID 1657 | Values: 110.0 110.0 110.0 115.0 158.0 164.0 165.0 111.0 110.0 112.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:119.70 Median:112.00 stddev:16.59 (14.8%) stddev-sans-first:15.89 12:23:11 INFO - PID 1657 | Values: 140.0 113.0 109.0 152.0 111.0 111.0 109.0 108.0 137.0 107.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:188.90 Median:186.00 stddev:11.74 (6.3%) stddev-sans-first:12.43 12:23:11 INFO - PID 1657 | Values: 187.0 182.0 185.0 185.0 188.0 184.0 221.0 192.0 180.0 185.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:71.30 Median:80.50 stddev:13.12 (16.3%) stddev-sans-first:13.33 12:23:11 INFO - PID 1657 | Values: 82.0 58.0 61.0 89.0 63.0 81.0 85.0 58.0 80.0 56.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:78.50 stddev:10.97 (14.0%) stddev-sans-first:10.59 12:23:11 INFO - PID 1657 | Values: 90.0 69.0 87.0 69.0 68.0 70.0 92.0 68.0 68.0 90.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:87.50 stddev:7.73 (8.8%) stddev-sans-first:8.11 12:23:11 INFO - PID 1657 | Values: 87.0 89.0 87.0 87.0 87.0 88.0 87.0 91.0 112.0 88.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 12:23:11 INFO - PID 1657 | Values: 94.0 94.0 95.0 95.0 94.0 93.0 95.0 95.0 95.0 94.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:0.63 (0.8%) stddev-sans-first:0.67 12:23:11 INFO - PID 1657 | Values: 84.0 83.0 83.0 84.0 85.0 84.0 84.0 83.0 84.0 84.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:1.51 (3.0%) stddev-sans-first:0.93 12:23:11 INFO - PID 1657 | Values: 53.0 48.0 48.0 49.0 50.0 48.0 49.0 50.0 50.0 50.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.50 stddev:3.41 (5.6%) stddev-sans-first:2.95 12:23:11 INFO - PID 1657 | Values: 54.0 61.0 60.0 66.0 63.0 61.0 57.0 57.0 59.0 58.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:82.50 stddev:3.30 (4.0%) stddev-sans-first:3.42 12:23:11 INFO - PID 1657 | Values: 83.0 81.0 83.0 83.0 82.0 83.0 81.0 81.0 81.0 72.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:138.00 stddev:16.08 (11.7%) stddev-sans-first:16.96 12:23:11 INFO - PID 1657 | Values: 138.0 138.0 135.0 137.0 132.0 135.0 126.0 145.0 169.0 175.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:187.50 stddev:16.84 (9.0%) stddev-sans-first:17.52 12:23:11 INFO - PID 1657 | Values: 184.0 188.0 187.0 188.0 222.0 228.0 186.0 185.0 185.0 181.0 12:23:11 INFO - PID 1657 | 12:23:11 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.70 Median:80.00 stddev:13.16 (16.4%) stddev-sans-first:13.26 12:23:11 INFO - PID 1657 | Values: 66.0 67.0 67.0 67.0 68.0 71.0 93.0 92.0 97.0 89.0 12:23:11 INFO - PID 1657 | -------- Summary: end -------- 12:23:11 INFO - PID 1657 | 12:23:13 INFO - TEST-INFO | 1657: exit 0 12:23:13 INFO - TEST-OK | kraken | took 61168ms 12:23:13 INFO - SUITE-END | took 1182s 12:23:13 INFO - Completed test suite (00:19:42) 12:23:13 INFO - javascript benchmark 12:23:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7615.747700686187, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3385.221399078212, 3446.052335607709, 3454.533637958875, 3493.9825300873486, 3529.3470707919023, 5798.942010579893, 6018.488428483573, 6071.848203794896, 6086.0, 6160.137580738695, 5911.290645122581, 5934.88130237395, 5951.137085122655, 5952.58331916766, 5962.761489540424, 5167.276581278624, 5183.403908550527, 5197.168453047513, 5271.683698978054, 5281.656692315007, 5333.040052790496, 5334.413214546396, 5342.091844386464, 5343.305370301855, 5351.678899266037, 3889.358255887775, 5327.174287985349, 5328.733563321838, 5333.093374126408, 5341.03861304965, 5275.155975043975, 5276.894462110737, 5280.102382594968, 5281.735913204325, 5286.180642000477, 5145.434002259748, 5268.183431568074, 5268.552173065255, 5274.129768588178, 5286.365636123652, 6150.138980542728, 6201.6899155042065, 6206.627602343829, 6214.347493513206, 6234.0337247726675, 6235.719392627343, 6404.551681382305, 6413.903791443132, 6431.067495213215, 6436.742530298782, 6323.14637523933, 6340.556161068727, 6348.301686814447, 6354.3010268870385, 6354.809355719336, 6041.154238406627, 6079.483243924272, 6101.450869421729, 6173.32093469718, 6254.21822272216, 6642.900356494656, 6654.467642588581, 6658.9001164982565, 6672.466202703772, 6686.4985126115735, 6353.078803573456, 6361.045843123568, 6375.10748495211, 6376.171097757355, 6383.968080159662, 6258.092576576371, 6292.182016337847, 6310.242770867525, 6357.364263573606, 6507.9023814642815, 6429.871402571923, 6433.324500927335, 6440.581362211441, 6452.9677351612945, 6459.386358295955, 6442.549021568445, 6453.870922581616, 6458.06358078076, 6459.903101453482, 6461.515386345949, 5108.642395032386, 5149.613778966593, 5178.145605974972, 5183.248428977777, 5252.080885844962, 4671.976640116778, 4676.672632915731, 4691.859244222679, 4709.717416954925, 4713.410823647044, 6086.452219300196, 6102.0, 6161.753529858755, 6165.475934545524, 6173.506119510428, 5672.489475947102, 6127.816165514952, 6332.715027823759, 6543.770968016097, 6577.736890524421, 5983.850403739942, 5994.820155395345, 6007.549433792396, 6010.849728756729, 6017.759289628366, 5621.15682647598, 5638.210650508932, 5639.097744360883, 5642.4075472075665, 5651.350094739158, 6336.891044067178, 6383.648899310582, 6387.297397286295, 6391.808245752635, 6404.135441715402, 6354.936450635434, 6355.777547785805, 6361.141245931925, 6372.426481616677, 6381.521385895983, 6480.611163330215, 6593.044008618819, 6604.3725846044545, 6609.4712423005085, 6611.768588099393, 6378.234611846423, 6391.073294372383, 6392.552521323462, 6393.6163830170335, 6402.231732192166, 6272.278687950761, 6343.619382837044, 6433.228012638513, 6442.677866106582, 6443.581167224115, 6266.342034086355, 6322.841928951813, 6325.55721099537, 6338.651374174465, 6339.651319177305, 6218.0, 6315.873682526416, 6318.6840657965995, 6328.272248691459, 6348.746050157942, 5119.385673719177, 6051.667158306335, 6164.47601953839, 6165.260168779775, 6175.96912015455, 6134.620279381683, 6280.403361680633, 6304.18045654062, 6305.558610897193, 6307.779227727007, 6147.586055207238, 6155.569110162246, 6255.40573645503, 6319.652419116809, 6336.9049464259], "value": 5894.049516060636, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.9308122647122, 809.4536188073051, 1137.9146564007697, 1156.768646270745, 1158.1371877950928, 35734.0, 36213.27573448533, 36613.81693091534, 36645.26709465809, 36680.89957301285, 38127.428088578694, 38389.616103838955, 38439.423408648894, 38509.807450962704, 38560.228795424075, 8491.448055876379, 8621.267192288631, 8624.913750862474, 8625.65497380104, 8643.827123457528, 8399.076101628847, 8559.186877246651, 8560.785980350478, 8561.44350617208, 8563.0, 8522.977242730882, 8544.957275213616, 8575.914240857574, 8587.484750914933, 8590.098039705834, 8520.147985201462, 8546.87179692302, 8554.914450855475, 8558.572071396467, 8617.224449799485, 8348.039975402846, 8423.494590324539, 8427.199416055464, 8431.620577073985, 8442.535660538668, 30292.03101798376, 36567.4514882277, 36685.81657091698, 36785.632143678486, 36789.44815827765, 3584.2831433713363, 3621.27574485104, 3622.1125824173246, 3624.0939765058733, 3639.581448133472, 29825.956091536693, 29911.252218694488, 29917.551236731466, 30005.549916751264, 30020.6470061842, 3168.1921110117005, 3174.016055022927, 3176.587043684306, 3176.857041433118, 3182.9363412731846, 241579.0, 257192.0, 303039.0, 304046.0, 304515.4774226137, 38257.61742382596, 38856.6114338853, 38952.41571376432, 46392.768036160276, 46711.0, 40939.59060409417, 40985.0, 41027.58972410238, 41033.589664102976, 48604.75697621489, 13528.32358382077, 13626.523071692443, 13718.245496497788, 13888.4444622216, 13900.652483687787, 11472.31166130035, 11476.88523114775, 11477.311361318347, 11481.311121332747, 11488.94255528717, 10146.695599132037, 10363.430011349297, 11779.234349767237, 11814.763704725858, 11942.462589183508, 4179.913222562095, 4823.975880120577, 5121.436641969381, 5313.1764576490705, 5437.429069947678, 1004.1063453526367, 1011.726833754882, 1038.4963292802984, 1047.8113939490966, 1058.7300238439302, 22948.88525557395, 24227.39431514191, 24264.0, 24319.392015199763, 24419.23824854172, 970.8980557041409, 1079.611339917629, 1108.944552772358, 1121.85415895933, 1122.405125283601, 9603.663871764455, 11986.940065299617, 12371.0, 12392.318422486669, 12393.442295096562, 248903.0, 298777.0, 298879.0, 299651.0, 319991.0, 52643.0, 52968.47031529713, 52973.735131324094, 52973.735131324094, 53219.73390133025, 1453.273363318341, 1486.3682934752471, 1498.4380857178558, 1498.5279636914338, 1527.3356090100842, 27619.44761104807, 27801.721982779913, 28578.28554286076, 28664.57003144913, 28687.426251474437, 36469.27061458809, 36489.63510364862, 36513.269734604626, 36533.817330913174, 36563.63436365602, 262007.37992620593, 262950.0, 263264.0, 263321.0, 265109.6744516265, 27751.722482774912, 28124.43751125007, 28242.87028518836, 31321.06036818899, 31418.842905785325, 40900.386494202015, 41718.0, 41730.95672608209, 41767.373489397076, 41871.7906410466, 27426.862865685544, 28495.857520712263, 29039.27401814887, 29612.40775184441, 29835.0, 28592.42815143727, 28868.566971495864, 29362.55956160702, 29564.96522621698, 29644.04645166384, 34635.4804677925, 34666.82666586651, 34682.653173467945, 34733.826330868185, 34750.47874281837, 28158.71841281561, 28178.577321340606, 28978.855105724335, 29111.0, 30005.460412012908, 18156.43011349009, 18674.346397876554, 21626.372748293325, 21739.56520869542, 22481.763503006838], "value": 16842.08628736085, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.59435809325925, 412.39378114172155, 419.34582051998905, 424.92563801334757, 438.62716690812795, 19772.70340944887, 20709.171633134676, 20892.791072089276, 21472.0, 21615.67576486354, 21842.235521756742, 21994.560108797854, 22073.33779986603, 22519.774802252014, 22738.976746046425, 5741.339745929219, 6255.843603909916, 6259.155014073087, 6268.091126786613, 6270.529710271725, 5988.7604495820115, 6131.509479241672, 6185.4433101020895, 6274.466670333027, 6292.779752708656, 6213.720382582772, 6229.719662615171, 6242.406971337716, 6246.968765156168, 6248.062790581426, 5974.133750606159, 6145.969270153643, 6220.066989951498, 6228.034654628563, 6232.688365581703, 5304.655197412156, 5444.6188766786345, 5521.365043020064, 5527.861803454945, 5531.474509921592, 26435.867820660966, 26438.47123057528, 26553.0, 26682.665866706782, 26832.329191770168, 2202.878841663708, 2236.575050740354, 2271.943201419961, 2272.5909336319455, 2277.5786479501344, 17426.65146697059, 17605.911970440065, 17926.641467170713, 17954.371596994042, 18122.184501697324, 2075.6886467029917, 2157.6332023556033, 2186.7703891091523, 2187.9781202187933, 2189.638709612912, 141740.2912985439, 142362.57637423597, 143039.55834458722, 143302.0, 145370.81943770853, 11177.888221117848, 11683.591074312359, 11709.648710538697, 11714.769949155216, 11911.463984120735, 15765.132917689407, 15772.369105235663, 15798.946137002345, 15800.841991580168, 15808.68382632341, 8429.957850210709, 8754.0, 8757.080506546848, 8859.557022148867, 8903.777405564782, 8378.622961966727, 8394.45436046655, 8414.200650938152, 8578.142185781371, 8598.742037738877, 6026.668533230627, 6077.665728384981, 7230.891536626955, 7324.304191101837, 7326.706931722778, 2017.3544465771106, 2023.8684485508395, 2024.2914979756968, 2027.2093883385492, 2047.805458481442, 924.6301479408156, 953.5613617735919, 957.2150836314156, 1001.4742260313394, 1014.5992333028413, 19165.712514312017, 20093.598128037356, 20840.0, 20954.89522552408, 21015.369538913856, 949.1837020162654, 993.5280741647659, 1024.7950409918046, 1026.343140390151, 1039.3815679670583, 8871.733847984571, 10204.795904081877, 10740.570377184982, 10924.672259832218, 11072.3910184939, 551553.0, 562729.0, 567993.0, 568105.0, 576600.0, 103744.0, 110606.89393106126, 116861.0, 135515.96726049116, 142719.0, 1135.8903487183345, 1165.609520810521, 1214.2775048845922, 1224.8775122487857, 1226.068188176974, 21725.456863577903, 22017.313510329222, 22134.889325553268, 22233.22183723562, 22352.882355882466, 57806.71096644658, 58244.12633810411, 58571.0, 59728.40271597228, 59792.40207597868, 80778.59610701908, 82441.58779206306, 82490.0, 83534.16465835265, 84013.57993209995, 7521.172671006076, 8082.949216601804, 8546.401751877558, 8714.607842646968, 8861.33539984491, 13881.72236555243, 13897.722045559234, 14187.290635467978, 14189.716205676035, 14295.785563216352, 11496.252743571642, 11750.823737644114, 11793.0, 11869.821952670543, 11880.287182769061, 11126.998570128728, 11159.507583865583, 11223.438828058728, 11552.768944621228, 11666.008389287006, 10030.64892728751, 11419.60031398897, 11705.297682139098, 11856.11079169083, 12204.267743935392, 8695.956520217358, 8739.820124283395, 8818.971636523764, 8924.0, 9041.50271735061, 11150.386728729998, 11282.266652667551, 12156.331401772633, 12430.0, 12430.254184748583], "value": 10336.605863761415, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1397.9021468497206, 1468.6915747692983, 1478.5268714011506, 1493.4922126477, 1494.4769330734237, 11215.271007384514, 11659.300441973477, 11665.475053622586, 11666.82499762502, 11679.299242045461, 11282.153838462107, 11363.034142097931, 11642.941785291061, 11678.883211167908, 11687.474063667134, 2498.4503409249983, 2543.8855251513633, 2545.0, 2546.2997675639253, 2552.668153140089, 2491.327341617762, 2494.87525623719, 2532.708738495069, 2540.3649087728068, 2553.4637726077544, 2408.8795560222006, 2451.472933319336, 2526.873656317186, 2541.199522150526, 2553.042609021617, 2414.6860908081835, 2479.429731161834, 2488.4276616378243, 2526.2421273618024, 2526.5831137862233, 2449.4366295752075, 2476.418041760185, 2478.5414698280697, 2479.776820086183, 2494.600863861773, 11469.426528673534, 11710.590129345432, 11710.765784684343, 11714.941425292904, 11727.765444691058, 6408.551401401904, 6675.365840245169, 6678.6660666966945, 6680.5991640501725, 6686.765963191265, 4015.8795236142964, 4241.766702831343, 4271.0, 4345.760983145926, 4355.542668019875, 1217.1844863941092, 1231.7721221574038, 1247.4698253242427, 1289.929053902035, 1295.307010749254, 15037.248137593077, 15476.690466190727, 15943.28255228506, 16317.265723042492, 16439.753403698953, 4774.594159496412, 4901.975490122598, 5078.08594453002, 5501.587380946445, 5632.436756324417, 12282.867868693425, 12301.507939682491, 12304.938475307566, 12315.13794034409, 12321.322327272086, 5677.545796336283, 5863.442972917566, 6495.447886929664, 6495.837604059841, 6508.739650413931, 6021.2473440819895, 6040.184575082308, 6168.753249869955, 6180.165677633462, 6213.099100630383, 4288.378185163134, 4372.191144638252, 4426.424564806619, 4436.0240747035605, 4449.644028477714, 1068.7435015596357, 1252.1673087396962, 1269.9924594197846, 1271.961841144767, 1273.1533530202496, 788.8027993001749, 792.8929594504783, 809.8866158737782, 812.9390295727844, 818.0796603820702, 6032.939670603326, 6077.186223058563, 6269.905951410732, 6360.618362898241, 6394.0, 571.2602180176681, 583.7489879351834, 587.5857520448159, 592.7925226170892, 597.8355952113117, 3024.9261512162866, 3444.225049363873, 3555.306715190565, 3781.1870447853844, 3785.981070094632, 14582.270886455635, 15230.238488075773, 15231.314590843438, 15256.237188140549, 15257.923710381378, 14663.853361466248, 15154.090754554541, 15228.086314820925, 15275.236238188047, 15286.617834554225, 8500.44747091437, 8608.09614990417, 8612.440191387539, 8613.870791938127, 8636.438631488933, 4769.904601907873, 6714.731410743516, 6720.798376048726, 6920.100386949664, 7100.857982840211, 12429.0, 12714.936425317814, 12741.426635801596, 12861.97104231678, 12884.48462061507, 12683.873161268639, 12969.870301296865, 13106.410211540313, 13147.276899770604, 13229.07396482237, 8487.36344774132, 8580.227779499875, 8701.521416322163, 9551.665691700757, 9631.9036809631, 10843.0, 10981.780364392827, 10984.560617575207, 10986.340819550853, 10991.780164396827, 8967.372283940062, 9144.039875812943, 9225.475038651279, 9266.768330791785, 9383.202427793714, 8840.867386989328, 8885.733427997171, 9010.098990100938, 9038.548072596475, 9081.954590227007, 7793.883091753515, 7963.601819908866, 8060.428216497727, 8069.636866341146, 8072.556009419538, 5962.2249107615735, 5978.581499295007, 6060.636361818305, 6181.103739957591, 6207.441330280297, 5962.612430192024, 5973.253343332083, 6088.0, 6149.954507733606, 6304.527160463075], "value": 5253.26382325292, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.8275910322149, 375.6637809160801, 387.87781848717634, 389.61233572595273, 390.67769090500326, 5469.808556700516, 5769.624974376663, 5821.767129314822, 5835.941640583598, 5839.503642190413, 4288.528261891197, 4719.150552900476, 5236.8690782730355, 5450.182472629108, 5450.182472629108, 4238.88055972014, 4248.83004679814, 4249.171411574729, 4249.723767955088, 4269.658427325814, 3537.6992955598803, 4236.428082208909, 4243.702940794145, 4253.255680255958, 4253.383259427381, 4027.8388864445533, 4166.166766646667, 4188.518320393163, 4196.643285320752, 4259.914801703964, 4207.579242075799, 4218.557051509593, 4230.957690423087, 4231.1749208904275, 4241.957580424187, 3125.843707814623, 3641.217138315275, 3679.3259067124395, 3686.9078273043388, 3700.870469533553, 5205.479452054802, 5362.758675859595, 5546.55627549795, 5548.528375088123, 5572.024895643287, 4142.565030671796, 4931.408231012266, 4983.626228032912, 4992.40091189056, 5000.5249501297685, 5484.601426636222, 6048.848778780522, 6071.785642871443, 6083.11794789753, 6254.280757712876, 3774.8678796241998, 3903.8243279052226, 4083.5099788025627, 4139.834406623731, 4184.0, 5851.707414629294, 5976.880462390728, 6012.669303188323, 6223.315435302113, 6253.280872699653, 4116.300228961084, 4197.216183122161, 4200.306949353384, 4204.894877628084, 4205.642520385741, 4248.490181178278, 4258.233517966796, 4260.723053001545, 4284.76433796138, 4285.335772955197, 4653.720226937619, 4756.8097276108565, 4870.561649451566, 4910.263460480885, 4924.60603151747, 6109.0, 6113.541484388688, 6124.785632502841, 6138.404014956143, 6149.2620885494025, 1328.9202647840966, 1348.7504811609883, 1388.7083712420304, 1454.7526920423554, 1464.3044553836842, 794.3327604811985, 953.1850268020881, 965.8985806490358, 970.4032020307601, 977.1108291454884, 5545.61180717354, 5686.91948529778, 5839.766409343579, 5839.970800145972, 5842.0, 5477.397486276506, 5760.798372056958, 6285.685715714196, 6316.44184105438, 6325.272593651789, 5436.456354364532, 5490.121580547173, 5822.184894114827, 5825.650460972355, 5844.444777746107, 3243.772935894512, 3291.8643068141455, 3305.289362787012, 3309.5107201759306, 3331.1505566080496, 4988.603191106496, 6050.759594283161, 6460.483161347081, 6491.060679478224, 6500.577462465019, 5543.417941116125, 6403.615783053032, 6478.117237829121, 6499.2850786413455, 6512.185976752906, 5183.429822719573, 6072.089186621954, 6429.903551446732, 6440.323766004596, 6479.481641468671, 5437.945620543902, 6248.375162483715, 6258.217722784652, 6265.96867015662, 6320.273168585488, 5352.357717073976, 5707.343655479673, 5950.434708702666, 6162.383761623981, 6277.0541132249145, 5394.892102157856, 5790.913136302875, 6355.364463553793, 6482.57863238888, 6488.610683359014, 3837.1941892201835, 4087.4890638670167, 4193.903048475762, 4200.390943313264, 4206.305959516645, 6014.458698717136, 6259.624422534663, 6332.271788744353, 6332.398422149889, 6353.618782873043, 5078.238264260391, 5145.0, 6037.245344331958, 6071.605345652519, 6094.238220222473, 5731.8853622928145, 6049.818505444844, 6061.15143879866, 6093.390660934048, 6112.786052488142, 5158.664686795346, 5510.586705996986, 5963.0, 5989.940100598938, 6012.0, 5612.85967850807, 6059.787907423219, 6099.9390006099375, 6125.877482450415, 6127.325994140552, 4747.572718455355, 5413.83758487246, 5693.629914055573, 5941.494972927349, 5957.017092179742], "value": 4424.968710434008, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.5724565260841, 763.9503432276903, 869.4739682492092, 873.580681272989, 883.2183517586938, 13504.932475337622, 17861.464156075323, 17988.55028624285, 18590.256389744394, 18611.99999999998, 14631.634209144777, 17596.944183348976, 18234.452966410994, 18241.817581824176, 18347.724784128248, 957.0190554681456, 980.9068138526865, 988.4365911430488, 989.717930389839, 989.8515222716578, 673.2560520624712, 689.7137687859532, 695.1276148433709, 996.4668902137358, 1005.6731562242264, 757.9431542634325, 759.2786852490157, 980.9901900980971, 1036.9170466362712, 1043.8642976413093, 1033.4729288063104, 1035.6116456328878, 1036.207301414411, 1036.6164519127894, 1038.4132964874898, 769.3191525499917, 769.7536788227769, 772.8415674786655, 773.1109224391938, 775.1241097559721, 11920.284782913053, 12325.753484930341, 16240.43158489459, 16678.749818752727, 17052.744208836753, 16721.080340581262, 16805.747913781302, 17394.956302621882, 17621.0, 17694.55763605907, 13879.930600347034, 13973.860261397358, 14173.574792756233, 14297.928510357486, 14299.428022879176, 3411.9658803412144, 3432.0733401981574, 3453.8100404477746, 3457.8098204598737, 3465.5841299044278, 94491.52754236184, 123815.76184238133, 123886.0, 125288.0, 126210.0, 11407.0, 13514.391852366667, 13615.251161186032, 13651.453941842414, 13668.384922678504, 20072.598548028956, 20449.591008179752, 20498.0, 24795.5040899181, 24964.625530617057, 6926.480513961473, 7784.182660820646, 8014.79963000918, 8038.39712021589, 8047.114817370085, 3867.748596341285, 3885.3977633466857, 3896.8636097736444, 3942.6845852331744, 3953.920921581552, 4358.869233922987, 4483.843065492692, 4484.0583477469445, 4487.057717879216, 4495.2358099122575, 915.5651065743718, 949.520492151459, 960.2605993385055, 960.3421656165549, 960.6589660670501, 608.812815010722, 649.8862699027651, 680.6766785776716, 683.6855046678484, 689.2865883810232, 9312.627494900127, 10349.534270957825, 11612.88387116118, 11874.762504749857, 11965.162438629213, 636.6752955992384, 648.6108334999058, 650.6193876582158, 664.259350823828, 683.7948615415355, 4542.795574199169, 5374.70439125844, 5455.427180146107, 5512.862178445491, 5646.85882852932, 90408.1918361629, 103429.0, 104520.0, 104607.4769626147, 106926.0, 69462.65268673624, 70533.29466705267, 88429.11570884337, 89895.0, 92996.0, 64701.67649161724, 82229.76655350174, 83122.58438707767, 83653.0, 84508.57745711356, 8799.516026618598, 9579.952100239454, 10878.673639790819, 11203.551857925591, 11398.658040258806, 42519.14961700687, 42631.786841065594, 43128.70613881588, 43623.34564981464, 43858.780706096266, 54199.45800542102, 54813.1778023322, 55470.72264638651, 55902.0, 55993.16010259931, 14195.148291102567, 14397.496087636882, 14405.567832965027, 14474.276286185437, 16547.83452165511, 17761.00504009753, 18450.630987380446, 18674.719879202094, 18747.34384296543, 18789.436316910516, 16981.66036679235, 17119.14404279756, 17234.482965511055, 17366.913165434093, 17370.044647544506, 17156.571085722957, 17267.222974965905, 17363.56591085233, 17409.738853916948, 17550.473485795446, 16176.352945882032, 16426.83573164253, 16691.91654041722, 16930.068846213577, 17012.829871701124, 10682.359058456517, 11548.538058477567, 11683.591074312359, 11777.175597708077, 12070.818937715765, 12964.675883102998, 13535.0, 13608.795868061788, 13655.156553865101, 13703.040787144804], "value": 8179.2787913786315, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1456.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 110.0, 115.0, 158.0, 164.0, 165.0, 111.0, 110.0, 112.0], "value": 126.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 109.0, 152.0, 111.0, 111.0, 109.0, 108.0, 137.0, 107.0], "value": 119.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 185.0, 185.0, 188.0, 184.0, 221.0, 192.0, 180.0, 185.0], "value": 188.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 61.0, 89.0, 63.0, 81.0, 85.0, 58.0, 80.0, 56.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 87.0, 69.0, 68.0, 70.0, 92.0, 68.0, 68.0, 90.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 87.0, 87.0, 87.0, 88.0, 87.0, 91.0, 112.0, 88.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 93.0, 95.0, 95.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 84.0, 85.0, 84.0, 84.0, 83.0, 84.0, 84.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 48.0, 48.0, 49.0, 50.0, 48.0, 49.0, 50.0, 50.0, 50.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 61.0, 60.0, 66.0, 63.0, 61.0, 57.0, 57.0, 59.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 83.0, 83.0, 82.0, 83.0, 81.0, 81.0, 81.0, 72.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 135.0, 137.0, 132.0, 135.0, 126.0, 145.0, 169.0, 175.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 187.0, 188.0, 222.0, 228.0, 186.0, 185.0, 185.0, 181.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 68.0, 71.0, 93.0, 92.0, 97.0, 89.0], "value": 77.7, "unit": "score"}]}]} 12:23:13 INFO - Return code: 0 12:23:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:23:13 ERROR - # TBPL SUCCESS # 12:23:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:13 INFO - 2017-04-16 12:23:13.650 screenresolution[1662:13177] starting screenresolution argv=screenresolution get 12:23:13 INFO - 2017-04-16 12:23:13.653 screenresolution[1662:13177] Display 0: 1600x1200x32@60 12:23:13 INFO - 2017-04-16 12:23:13.659 screenresolution[1663:13180] starting screenresolution argv=screenresolution list 12:23:13 INFO - Available Modes on Display 0 12:23:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:13 INFO - Intel Iris: 12:23:13 INFO - Chipset Model: Intel Iris 12:23:13 INFO - Type: GPU 12:23:13 INFO - Bus: Built-In 12:23:13 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:13 INFO - Vendor: Intel (0x8086) 12:23:13 INFO - Device ID: 0x0a2e 12:23:13 INFO - Revision ID: 0x0009 12:23:13 INFO - Displays: 12:23:13 INFO - Display: 12:23:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:13 INFO - Display Serial Number: 1600x1200 60 12:23:13 INFO - Main Display: Yes 12:23:13 INFO - Mirror: Off 12:23:13 INFO - Online: Yes 12:23:13 INFO - Rotation: Supported 12:23:13 INFO - Return code: 0 12:23:13 INFO - Running post-action listener: _resource_record_post_action 12:23:13 INFO - [mozharness: 2017-04-16 19:23:13.893372Z] Finished run-tests step (success) 12:23:13 INFO - Running post-run listener: _resource_record_post_run 12:23:13 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 32734720; Write bytes: 516409856; Read time: 291; Write time: 1587 12:23:13 INFO - TinderboxPrint: CPU usage
53.2% 12:23:13 INFO - TinderboxPrint: I/O read bytes / time
32,734,720 / 291 12:23:13 INFO - TinderboxPrint: I/O write bytes / time
516,409,856 / 1,587 12:23:13 INFO - TinderboxPrint: CPU idle
2,250.8 (46.8%) 12:23:13 INFO - TinderboxPrint: CPU system
958.8 (19.9%) 12:23:13 INFO - TinderboxPrint: CPU user
1,604.2 (33.3%) 12:23:13 INFO - TinderboxPrint: Swap in / out
236,961,792 / 0 12:23:13 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204794368; Write bytes: 205890048; Read time: 16358; Write time: 441 12:23:13 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 30756352; Write bytes: 303712256; Read time: 272; Write time: 1120 12:23:14 INFO - Running post-run listener: _upload_blobber_files 12:23:14 INFO - Blob upload gear active. 12:23:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:23:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:23:14 INFO - (blobuploader) - INFO - Open directory for files ... 12:23:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:23:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:23:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:15 INFO - (blobuploader) - INFO - Done attempting. 12:23:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:23:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:23:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:16 INFO - (blobuploader) - INFO - Done attempting. 12:23:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:23:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:23:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:17 INFO - (blobuploader) - INFO - Done attempting. 12:23:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:23:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:23:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:20 INFO - (blobuploader) - INFO - Done attempting. 12:23:20 INFO - (blobuploader) - INFO - Iteration through files over. 12:23:20 INFO - Return code: 0 12:23:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:23:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:23:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f53346d14d4e1e36542eda20fc8c449909d1ba6b6c704ad7d970966e2508ecbf8420d686182102293a9b3246b5cf05ecb106e0809e5bce89a8991e2cad8fb73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b99598958340ce6c36560f010166435be5a24a949a58f878207369583d453399070d7351d05bf81cc9b5a27aec4e93112354d959295b8cc44837b42de2dfc661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8ef1162b1118fbcf6550e884170b1076cd019ef25004cae8fd1e934b023cb8a902235081b897457565b29350897bce449e2b48581582c42ceed3232971de17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53d22ac400debdc10b514b5423dce65eab3fa8666f85c4a4a5580b8f6a2465f5251863328d5dd569b6f87bfc264edaa27bc24915c452dcffada2f670ff900b4a"} 12:23:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:23:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:23:20 INFO - Contents: 12:23:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f53346d14d4e1e36542eda20fc8c449909d1ba6b6c704ad7d970966e2508ecbf8420d686182102293a9b3246b5cf05ecb106e0809e5bce89a8991e2cad8fb73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b99598958340ce6c36560f010166435be5a24a949a58f878207369583d453399070d7351d05bf81cc9b5a27aec4e93112354d959295b8cc44837b42de2dfc661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8ef1162b1118fbcf6550e884170b1076cd019ef25004cae8fd1e934b023cb8a902235081b897457565b29350897bce449e2b48581582c42ceed3232971de17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53d22ac400debdc10b514b5423dce65eab3fa8666f85c4a4a5580b8f6a2465f5251863328d5dd569b6f87bfc264edaa27bc24915c452dcffada2f670ff900b4a"} 12:23:20 INFO - Running post-run listener: copy_logs_to_upload_dir 12:23:20 INFO - Copying logs to upload dir... 12:23:20 INFO - mkdir: /builds/slave/test/build/upload/logs 12:23:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.188285 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-16 12:23:20.144083) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-16 12:23:20.147268) ========= 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.LmxF1PXh6V/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.EokiCmZl1c/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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/Firefox/sha512/8f53346d14d4e1e36542eda20fc8c449909d1ba6b6c704ad7d970966e2508ecbf8420d686182102293a9b3246b5cf05ecb106e0809e5bce89a8991e2cad8fb73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b99598958340ce6c36560f010166435be5a24a949a58f878207369583d453399070d7351d05bf81cc9b5a27aec4e93112354d959295b8cc44837b42de2dfc661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8ef1162b1118fbcf6550e884170b1076cd019ef25004cae8fd1e934b023cb8a902235081b897457565b29350897bce449e2b48581582c42ceed3232971de17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53d22ac400debdc10b514b5423dce65eab3fa8666f85c4a4a5580b8f6a2465f5251863328d5dd569b6f87bfc264edaa27bc24915c452dcffada2f670ff900b4a"} build_url:https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010913 build_url: 'https://queue.taskcluster.net/v1/task/b2J4cj8eTVCW_-4aWVsVgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f53346d14d4e1e36542eda20fc8c449909d1ba6b6c704ad7d970966e2508ecbf8420d686182102293a9b3246b5cf05ecb106e0809e5bce89a8991e2cad8fb73", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b99598958340ce6c36560f010166435be5a24a949a58f878207369583d453399070d7351d05bf81cc9b5a27aec4e93112354d959295b8cc44837b42de2dfc661", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca8ef1162b1118fbcf6550e884170b1076cd019ef25004cae8fd1e934b023cb8a902235081b897457565b29350897bce449e2b48581582c42ceed3232971de17", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53d22ac400debdc10b514b5423dce65eab3fa8666f85c4a4a5580b8f6a2465f5251863328d5dd569b6f87bfc264edaa27bc24915c452dcffada2f670ff900b4a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-16 12:23:20.180807) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:23:20.184168) ========= 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.LmxF1PXh6V/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.EokiCmZl1c/Listeners TMPDIR=/var/folders/46/dyhgxyws7270nkhp4l7t8z0800000w/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.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-16 12:23:20.232649) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-16 12:23:20.236002) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-16 12:23:20.236338) ========= ========= Total master_lag: 0.19 =========