builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0297 starttime: 1478349683.92 results: success (0) buildid: 20161105030211 builduid: 453ca8f7bf4646ff8a8032a5ecb94351 revision: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:23.917823) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:23.918324) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:23.918742) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:23.996966) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:23.997345) ========= 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.YiETun6bOR/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.wwXwIYbfzC/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-05 05:41:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-11-05 05:41:24 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152942 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:24.175612) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:24.175946) ========= 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.YiETun6bOR/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.wwXwIYbfzC/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.019987 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:24.240599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 05:41:24.240971) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd --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 a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YiETun6bOR/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.wwXwIYbfzC/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-05 05:41:24,367 truncating revision to first 12 chars 2016-11-05 05:41:24,367 Setting DEBUG logging. 2016-11-05 05:41:24,367 attempt 1/10 2016-11-05 05:41:24,367 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a7c654513f2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 05:41:25,493 unpacking tar archive at: mozilla-central-a7c654513f2f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.372486 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 05:41:25.638909) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:25.639258) ========= script_repo_revision: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:25.639734) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:25.640010) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 05:41:25.658375) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-05 05:41:25.658683) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.YiETun6bOR/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.wwXwIYbfzC/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:41:25 INFO - MultiFileLogger online at 20161105 05:41:25 in /builds/slave/test 05:41:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:41:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:41:25 INFO - {'append_to_log': False, 05:41:25 INFO - 'base_work_dir': '/builds/slave/test', 05:41:25 INFO - 'blob_upload_branch': 'Firefox', 05:41:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:41:25 INFO - 'branch': 'Firefox', 05:41:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:41:25 INFO - 'config_files': ('talos/mac_config.py',), 05:41:25 INFO - 'default_actions': ('clobber', 05:41:25 INFO - 'read-buildbot-config', 05:41:25 INFO - 'download-and-extract', 05:41:25 INFO - 'populate-webroot', 05:41:25 INFO - 'create-virtualenv', 05:41:25 INFO - 'install', 05:41:25 INFO - 'run-tests'), 05:41:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:41:25 INFO - 'download_minidump_stackwalk': True, 05:41:25 INFO - 'download_symbols': 'ondemand', 05:41:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:41:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:41:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:41:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:41:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:41:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:41:25 INFO - 'installer_path': 'installer.exe', 05:41:25 INFO - 'log_level': 'info', 05:41:25 INFO - 'log_name': 'talos', 05:41:25 INFO - 'log_to_console': True, 05:41:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:41:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:41:25 INFO - 'opt_config_files': (), 05:41:25 INFO - 'pip_index': False, 05:41:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:41:25 INFO - 'cmd': ('bash', 05:41:25 INFO - '-c', 05:41:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:41:25 INFO - 'enabled': True, 05:41:25 INFO - 'halt_on_failure': False, 05:41:25 INFO - 'name': 'check_screen_resolution'},), 05:41:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:41:25 INFO - 'cmd': ('bash', 05:41:25 INFO - '-c', 05:41:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:41:25 INFO - 'enabled': True, 05:41:25 INFO - 'halt_on_failure': False, 05:41:25 INFO - 'name': 'check_screen_resolution'},), 05:41:25 INFO - 'run_cmd_checks_enabled': True, 05:41:25 INFO - 'sps_profile': False, 05:41:25 INFO - 'sps_profile_interval': 0, 05:41:25 INFO - 'suite': 'dromaeojs-e10s', 05:41:25 INFO - 'system_bits': '32', 05:41:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:41:25 INFO - 'title': 't-yosemite-r7-0297', 05:41:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:41:25 INFO - 'use_talos_json': True, 05:41:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:41:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:41:25 INFO - 'work_dir': 'build'} 05:41:25 INFO - [mozharness: 2016-11-05 12:41:25.907590Z] Running clobber step. 05:41:25 INFO - Running pre-action listener: _resource_record_pre_action 05:41:25 INFO - Running main action method: clobber 05:41:25 INFO - rmtree: /builds/slave/test/build 05:41:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:41:26 INFO - Running post-action listener: _resource_record_post_action 05:41:26 INFO - [mozharness: 2016-11-05 12:41:26.373775Z] Finished clobber step (success) 05:41:26 INFO - [mozharness: 2016-11-05 12:41:26.373930Z] Running read-buildbot-config step. 05:41:26 INFO - Running pre-action listener: _resource_record_pre_action 05:41:26 INFO - Running main action method: read_buildbot_config 05:41:26 INFO - Using buildbot properties: 05:41:26 INFO - { 05:41:26 INFO - "project": "", 05:41:26 INFO - "product": "firefox", 05:41:26 INFO - "script_repo_revision": "production", 05:41:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:41:26 INFO - "repository": "", 05:41:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:41:26 INFO - "buildid": "20161105030211", 05:41:26 INFO - "stage_platform": "macosx64", 05:41:26 INFO - "basedir": "/builds/slave/test", 05:41:26 INFO - "nightly_build": "True", 05:41:26 INFO - "buildnumber": 164, 05:41:26 INFO - "slavename": "t-yosemite-r7-0297", 05:41:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:41:26 INFO - "platform": "yosemite_r7", 05:41:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:41:26 INFO - "branch": "mozilla-central", 05:41:26 INFO - "repo_path": "mozilla-central", 05:41:26 INFO - "revision": "a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd", 05:41:26 INFO - "pgo_build": "False", 05:41:26 INFO - "builduid": "453ca8f7bf4646ff8a8032a5ecb94351", 05:41:26 INFO - "slavebuilddir": "test" 05:41:26 INFO - } 05:41:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:41:26 INFO - Running post-action listener: _resource_record_post_action 05:41:26 INFO - [mozharness: 2016-11-05 12:41:26.377475Z] Finished read-buildbot-config step (success) 05:41:26 INFO - [mozharness: 2016-11-05 12:41:26.377615Z] Running download-and-extract step. 05:41:26 INFO - Running pre-action listener: _resource_record_pre_action 05:41:26 INFO - Running main action method: download_and_extract 05:41:26 INFO - mkdir: /builds/slave/test/build/tests 05:41:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:26 INFO - trying https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:41:26 INFO - Downloading https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:41:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:41:27 INFO - Downloaded 1199 bytes. 05:41:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:41:27 INFO - Using the following test package requirements: 05:41:27 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:41:27 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:41:27 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:41:27 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'jsshell-mac.zip'], 05:41:27 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:41:27 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:41:27 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:41:27 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:41:27 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:41:27 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:41:27 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:41:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:41:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:41:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:41:27 INFO - Fetch https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:41:28 INFO - Http code: 200 05:41:28 INFO - via: 1.1 75d3bdff1b8eb2920f04077d77f16ca0.cloudfront.net (CloudFront) 05:41:28 INFO - x-amz-cf-id: D-lcWeERuucGuDaYLNJV38kziGqbDAbiT4rc48HRVz0RUNo2G9zfJA== 05:41:28 INFO - x-amz-version-id: CsYsCX8U811Lx6DJEbq9eMZ9T34ZD_Ed 05:41:28 INFO - Expected file size: 16295976 05:41:28 INFO - Obtained file size: 16295976 05:41:35 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:41:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:41:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:41:35 INFO - Fetch https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:41:35 INFO - Http code: 200 05:41:35 INFO - via: 1.1 55676da1e5c0a9c4e60a94a95b01dc04.cloudfront.net (CloudFront) 05:41:35 INFO - x-amz-cf-id: Rr8U-Lr4VNfFXvVsn6iVCfcBxuZoCV2ogEkyL0rsRrJAKZtRdEFf9w== 05:41:35 INFO - x-amz-version-id: CsYsCX8U811Lx6DJEbq9eMZ9T34ZD_Ed 05:41:36 INFO - Expected file size: 16295976 05:41:36 INFO - Obtained file size: 16295976 05:41:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:41:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:41:42 INFO - Fetch https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:41:43 INFO - Http code: 200 05:41:43 INFO - via: 1.1 02192a27c967e955f8c815efa939bfc8.cloudfront.net (CloudFront) 05:41:43 INFO - x-amz-cf-id: g48N88q8BBh-Xr0t5YZopV_P7Qjjwr36tM34yHziG7ewqQPH5Pn-_w== 05:41:43 INFO - x-amz-version-id: 3U0YQhxLoxA726fyKwFCi0f7lAdjNrTL 05:41:43 INFO - Expected file size: 14409038 05:41:43 INFO - Obtained file size: 14409038 05:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:44 INFO - trying https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:44 INFO - Downloading https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:41:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:41:46 INFO - Downloaded 111372399 bytes. 05:41:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:46 INFO - mkdir: /builds/slave/test/properties 05:41:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:41:46 INFO - Writing to file /builds/slave/test/properties/build_url 05:41:46 INFO - Contents: 05:41:46 INFO - build_url:https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:47 INFO - Running post-action listener: _resource_record_post_action 05:41:47 INFO - Running post-action listener: set_extra_try_arguments 05:41:47 INFO - [mozharness: 2016-11-05 12:41:47.520008Z] Finished download-and-extract step (success) 05:41:47 INFO - [mozharness: 2016-11-05 12:41:47.520150Z] Running populate-webroot step. 05:41:47 INFO - Running pre-action listener: _resource_record_pre_action 05:41:47 INFO - Running main action method: populate_webroot 05:41:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:41:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:41:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:41:47 INFO - '--tppagecycles', 05:41:47 INFO - '7'], 05:41:47 INFO - 'tests': ['tsvgm']}}, 05:41:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:41:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:41:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:41:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:41:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['damp', 'tps']}, 05:41:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'tests': ['damp', 'tps']}, 05:41:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['dromaeo_dom']}, 05:41:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:41:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['basic_compositor_video']}, 05:41:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:41:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['a11yr', 05:41:47 INFO - 'ts_paint', 05:41:47 INFO - 'tpaint', 05:41:47 INFO - 'sessionrestore', 05:41:47 INFO - 'sessionrestore_no_auto_restore', 05:41:47 INFO - 'tabpaint']}, 05:41:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:41:47 INFO - 'ts_paint', 05:41:47 INFO - 'tpaint', 05:41:47 INFO - 'sessionrestore', 05:41:47 INFO - 'sessionrestore_no_auto_restore', 05:41:47 INFO - 'tabpaint']}, 05:41:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:41:47 INFO - 'ts_paint', 05:41:47 INFO - 'tpaint', 05:41:47 INFO - 'sessionrestore', 05:41:47 INFO - 'sessionrestore_no_auto_restore', 05:41:47 INFO - 'tabpaint']}, 05:41:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:41:47 INFO - 'ts_paint', 05:41:47 INFO - 'tpaint', 05:41:47 INFO - 'sessionrestore', 05:41:47 INFO - 'sessionrestore_no_auto_restore', 05:41:47 INFO - 'tabpaint']}, 05:41:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['a11yr', 05:41:47 INFO - 'ts_paint', 05:41:47 INFO - 'tpaint', 05:41:47 INFO - 'sessionrestore', 05:41:47 INFO - 'sessionrestore_no_auto_restore', 05:41:47 INFO - 'tabpaint']}, 05:41:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['a11yr', 05:41:47 INFO - 'ts_paint', 05:41:47 INFO - 'tpaint', 05:41:47 INFO - 'sessionrestore', 05:41:47 INFO - 'sessionrestore_no_auto_restore', 05:41:47 INFO - 'tabpaint']}, 05:41:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['tsvgx', 05:41:47 INFO - 'tsvgr_opacity', 05:41:47 INFO - 'tart', 05:41:47 INFO - 'tscrollx', 05:41:47 INFO - 'cart']}, 05:41:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:41:47 INFO - 'tsvgr_opacity', 05:41:47 INFO - 'tart', 05:41:47 INFO - 'tscrollx', 05:41:47 INFO - 'cart']}, 05:41:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'talos_options': ['--disable-e10s'], 05:41:47 INFO - 'tests': ['tp5o']}, 05:41:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'tests': ['tp5o']}, 05:41:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'talos_options': ['--disable-e10s', 05:41:47 INFO - '--xperf_path', 05:41:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:47 INFO - 'tests': ['tp5n']}, 05:41:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:47 INFO - 'talos_options': ['--xperf_path', 05:41:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:47 INFO - 'tests': ['tp5n']}}, 05:41:47 INFO - 'talos.zip': {'path': '', 05:41:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:41:47 INFO - Running post-action listener: _resource_record_post_action 05:41:47 INFO - [mozharness: 2016-11-05 12:41:47.539214Z] Finished populate-webroot step (success) 05:41:47 INFO - [mozharness: 2016-11-05 12:41:47.539296Z] Running create-virtualenv step. 05:41:47 INFO - Running pre-action listener: _resource_record_pre_action 05:41:47 INFO - Running main action method: create_virtualenv 05:41:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:41:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:41:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:41:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:41:47 INFO - Using real prefix '/tools/python27' 05:41:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:41:48 INFO - Installing distribute.............................................................................................................................................................................................done. 05:41:51 INFO - Installing pip.................done. 05:41:51 INFO - Return code: 0 05:41:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:41:51 INFO - Reading from file tmpfile_stdout 05:41:51 INFO - Output received: 05:41:51 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) 05:41:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:41:51 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')]} 05:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:51 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')]} 05:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:51 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:51 INFO - 'HOME': '/Users/cltbld', 05:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:51 INFO - 'LOGNAME': 'cltbld', 05:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:51 INFO - 'MOZ_NO_REMOTE': '1', 05:41:51 INFO - 'NO_EM_RESTART': '1', 05:41:51 INFO - 'PAGER': '/bin/cat', 05:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:51 INFO - 'PWD': '/builds/slave/test', 05:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:51 INFO - 'SHELL': '/bin/bash', 05:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:41:51 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:51 INFO - 'USER': 'cltbld', 05:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:51 INFO - 'XPC_FLAGS': '0x0', 05:41:51 INFO - 'XPC_SERVICE_NAME': '0', 05:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:41:51 INFO - Cleaning up... 05:41:51 INFO - Return code: 0 05:41:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:41:51 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')]} 05:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:51 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')]} 05:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:51 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:41:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:41:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:51 INFO - 'HOME': '/Users/cltbld', 05:41:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:51 INFO - 'LOGNAME': 'cltbld', 05:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:51 INFO - 'MOZ_NO_REMOTE': '1', 05:41:51 INFO - 'NO_EM_RESTART': '1', 05:41:51 INFO - 'PAGER': '/bin/cat', 05:41:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:51 INFO - 'PWD': '/builds/slave/test', 05:41:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:51 INFO - 'SHELL': '/bin/bash', 05:41:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:41:51 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:41:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:51 INFO - 'USER': 'cltbld', 05:41:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:51 INFO - 'XPC_FLAGS': '0x0', 05:41:51 INFO - 'XPC_SERVICE_NAME': '0', 05:41:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:52 INFO - Downloading/unpacking psutil>=3.1.1 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41: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 05:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:41:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:55 INFO - Installing collected packages: psutil 05:41:55 INFO - Running setup.py install for psutil 05:41:55 INFO - building 'psutil._psutil_osx' extension 05:41:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:41:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:41:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:41:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:41:56 INFO - building 'psutil._psutil_posix' extension 05:41:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:41:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:41:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:41:56 INFO - ^ 05:41:56 INFO - 1 warning generated. 05:41:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:41:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:56 INFO - Successfully installed psutil 05:41:56 INFO - Cleaning up... 05:41:56 INFO - Return code: 0 05:41:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41: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')]} 05:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:56 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:41:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:56 INFO - 'HOME': '/Users/cltbld', 05:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:56 INFO - 'LOGNAME': 'cltbld', 05:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:56 INFO - 'MOZ_NO_REMOTE': '1', 05:41:56 INFO - 'NO_EM_RESTART': '1', 05:41:56 INFO - 'PAGER': '/bin/cat', 05:41:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:56 INFO - 'PWD': '/builds/slave/test', 05:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:56 INFO - 'SHELL': '/bin/bash', 05:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:41:56 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:56 INFO - 'USER': 'cltbld', 05:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:56 INFO - 'XPC_FLAGS': '0x0', 05:41:56 INFO - 'XPC_SERVICE_NAME': '0', 05:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:41:59 INFO - Installing collected packages: mozsystemmonitor 05:41:59 INFO - Running setup.py install for mozsystemmonitor 05:41:59 INFO - Successfully installed mozsystemmonitor 05:41:59 INFO - Cleaning up... 05:41:59 INFO - Return code: 0 05:41:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41: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')]} 05:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:59 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:41:59 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 05:41:59 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 05:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:59 INFO - 'HOME': '/Users/cltbld', 05:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:59 INFO - 'LOGNAME': 'cltbld', 05:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:59 INFO - 'MOZ_NO_REMOTE': '1', 05:41:59 INFO - 'NO_EM_RESTART': '1', 05:41:59 INFO - 'PAGER': '/bin/cat', 05:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:59 INFO - 'PWD': '/builds/slave/test', 05:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:59 INFO - 'SHELL': '/bin/bash', 05:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:41:59 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:59 INFO - 'USER': 'cltbld', 05:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:59 INFO - 'XPC_FLAGS': '0x0', 05:41:59 INFO - 'XPC_SERVICE_NAME': '0', 05:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:42:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:42:03 INFO - warning: no files found matching '*.txt' 05:42:03 INFO - no previously-included directories found matching 'build' 05:42:03 INFO - no previously-included directories found matching 'dist' 05:42:03 INFO - no previously-included directories found matching '.git*' 05:42:03 INFO - Installing collected packages: jsonschema, functools32 05:42:03 INFO - Running setup.py install for functools32 05:42:03 INFO - warning: no files found matching '*.txt' 05:42:03 INFO - no previously-included directories found matching 'build' 05:42:03 INFO - no previously-included directories found matching 'dist' 05:42:03 INFO - no previously-included directories found matching '.git*' 05:42:03 INFO - Successfully installed jsonschema functools32 05:42:03 INFO - Cleaning up... 05:42:03 INFO - Return code: 0 05:42:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:03 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:03 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 05:42:03 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 05:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:03 INFO - 'HOME': '/Users/cltbld', 05:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:03 INFO - 'LOGNAME': 'cltbld', 05:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:03 INFO - 'MOZ_NO_REMOTE': '1', 05:42:03 INFO - 'NO_EM_RESTART': '1', 05:42:03 INFO - 'PAGER': '/bin/cat', 05:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:03 INFO - 'PWD': '/builds/slave/test', 05:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:03 INFO - 'SHELL': '/bin/bash', 05:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:42:03 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:03 INFO - 'USER': 'cltbld', 05:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:03 INFO - 'XPC_FLAGS': '0x0', 05:42:03 INFO - 'XPC_SERVICE_NAME': '0', 05:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:42:03 INFO - Cleaning up... 05:42:03 INFO - Return code: 0 05:42:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:03 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:03 INFO - 'HOME': '/Users/cltbld', 05:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:03 INFO - 'LOGNAME': 'cltbld', 05:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:03 INFO - 'MOZ_NO_REMOTE': '1', 05:42:03 INFO - 'NO_EM_RESTART': '1', 05:42:03 INFO - 'PAGER': '/bin/cat', 05:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:03 INFO - 'PWD': '/builds/slave/test', 05:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:03 INFO - 'SHELL': '/bin/bash', 05:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:42:03 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:03 INFO - 'USER': 'cltbld', 05:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:03 INFO - 'XPC_FLAGS': '0x0', 05:42:03 INFO - 'XPC_SERVICE_NAME': '0', 05:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:42:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:42:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:42:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:07 INFO - Downloading docopt-0.6.1.tar.gz 05:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:42:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:42:07 INFO - Running setup.py install for blobuploader 05:42:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:42:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:42:07 INFO - Running setup.py install for requests 05:42:08 INFO - Running setup.py install for docopt 05:42:08 INFO - Successfully installed blobuploader requests docopt 05:42:08 INFO - Cleaning up... 05:42:08 INFO - Return code: 0 05:42:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42: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')]} 05:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42: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')]} 05:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:08 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:42:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:42:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:42:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:08 INFO - 'HOME': '/Users/cltbld', 05:42:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:08 INFO - 'LOGNAME': 'cltbld', 05:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:08 INFO - 'MOZ_NO_REMOTE': '1', 05:42:08 INFO - 'NO_EM_RESTART': '1', 05:42:08 INFO - 'PAGER': '/bin/cat', 05:42:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:08 INFO - 'PWD': '/builds/slave/test', 05:42:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:08 INFO - 'SHELL': '/bin/bash', 05:42:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:42:08 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:42:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:08 INFO - 'USER': 'cltbld', 05:42:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:08 INFO - 'XPC_FLAGS': '0x0', 05:42:08 INFO - 'XPC_SERVICE_NAME': '0', 05:42:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:42:08 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-6Msxmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-FPNeSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-SK_8fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-yQJg6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-sRbzhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-itHt8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-LM8TRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-UE1UYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Jh0mzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-WF1syY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:42:09 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-hI11wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:42:10 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-p4gu9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:42:10 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-8S8k4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:42:10 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-xNQ5jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:10 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-sQ47o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:42:10 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-7F8Q9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:42:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:42:10 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-SlEudY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:42:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:42:10 INFO - Running setup.py install for manifestparser 05:42:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:42:10 INFO - Running setup.py install for mozcrash 05:42:11 INFO - Running setup.py install for mozdebug 05:42:11 INFO - Running setup.py install for mozdevice 05:42:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Running setup.py install for mozfile 05:42:11 INFO - Running setup.py install for mozhttpd 05:42:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Running setup.py install for mozinfo 05:42:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Running setup.py install for mozInstall 05:42:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:42:11 INFO - Running setup.py install for mozleak 05:42:12 INFO - Running setup.py install for mozlog 05:42:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:42:12 INFO - Running setup.py install for moznetwork 05:42:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:42:12 INFO - Running setup.py install for mozprocess 05:42:12 INFO - Running setup.py install for mozprofile 05:42:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:42:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:42:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:42:12 INFO - Running setup.py install for mozrunner 05:42:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:42:12 INFO - Running setup.py install for mozscreenshot 05:42:13 INFO - Running setup.py install for moztest 05:42:13 INFO - Running setup.py install for mozversion 05:42:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:42:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:42:13 INFO - Cleaning up... 05:42:13 INFO - Return code: 0 05:42:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:13 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:42:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:42:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:13 INFO - 'HOME': '/Users/cltbld', 05:42:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:13 INFO - 'LOGNAME': 'cltbld', 05:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:13 INFO - 'MOZ_NO_REMOTE': '1', 05:42:13 INFO - 'NO_EM_RESTART': '1', 05:42:13 INFO - 'PAGER': '/bin/cat', 05:42:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:13 INFO - 'PWD': '/builds/slave/test', 05:42:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:13 INFO - 'SHELL': '/bin/bash', 05:42:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:42:13 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:42:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:13 INFO - 'USER': 'cltbld', 05:42:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:13 INFO - 'XPC_FLAGS': '0x0', 05:42:13 INFO - 'XPC_SERVICE_NAME': '0', 05:42:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:42:13 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Obo2uL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:42:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:42:13 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-zU8XcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:42:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:42:13 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-ztgAz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:42:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:42:13 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-8OFkEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-hesS_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-SaWhSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-VQKXIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-5Uwqdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-_d9QmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-F6YS2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:42:14 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)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-rTvN_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-ZTEOFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-gCVny4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:42:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:42:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:42:14 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-5iO6y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:15 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-PQ5rmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:42:15 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-bIRDSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:42:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:42:15 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-aELPxg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:42:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:42:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:42:17 INFO - Downloading blessings-1.6.tar.gz 05:42:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:42:18 INFO - Installing collected packages: blessings 05:42:18 INFO - Running setup.py install for blessings 05:42:18 INFO - Successfully installed blessings 05:42:18 INFO - Cleaning up... 05:42:18 INFO - Return code: 0 05:42:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:42:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:42:18 INFO - Reading from file tmpfile_stdout 05:42:18 INFO - Current package versions: 05:42:18 INFO - blessings == 1.6 05:42:18 INFO - blobuploader == 1.2.4 05:42:18 INFO - docopt == 0.6.1 05:42:18 INFO - functools32 == 3.2.3-2 05:42:18 INFO - jsonschema == 2.5.1 05:42:18 INFO - manifestparser == 1.1 05:42:18 INFO - mozInstall == 1.12 05:42:18 INFO - mozcrash == 0.17 05:42:18 INFO - mozdebug == 0.1 05:42:18 INFO - mozdevice == 0.48 05:42:18 INFO - mozfile == 1.2 05:42:18 INFO - mozhttpd == 0.7 05:42:18 INFO - mozinfo == 0.9 05:42:18 INFO - mozleak == 0.1 05:42:18 INFO - mozlog == 3.4 05:42:18 INFO - moznetwork == 0.27 05:42:18 INFO - mozprocess == 0.23 05:42:18 INFO - mozprofile == 0.28 05:42:18 INFO - mozrunner == 6.12 05:42:18 INFO - mozscreenshot == 0.1 05:42:18 INFO - mozsystemmonitor == 0.3 05:42:18 INFO - moztest == 0.7 05:42:18 INFO - mozversion == 1.4 05:42:18 INFO - psutil == 3.1.1 05:42:18 INFO - requests == 1.2.3 05:42:18 INFO - wsgiref == 0.1.2 05:42:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:18 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:18 INFO - 'HOME': '/Users/cltbld', 05:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:18 INFO - 'LOGNAME': 'cltbld', 05:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:18 INFO - 'MOZ_NO_REMOTE': '1', 05:42:18 INFO - 'NO_EM_RESTART': '1', 05:42:18 INFO - 'PAGER': '/bin/cat', 05:42:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:18 INFO - 'PWD': '/builds/slave/test', 05:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:18 INFO - 'SHELL': '/bin/bash', 05:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:42:18 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:18 INFO - 'USER': 'cltbld', 05:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:18 INFO - 'XPC_FLAGS': '0x0', 05:42:18 INFO - 'XPC_SERVICE_NAME': '0', 05:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:42:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:42:18 INFO - Cleaning up... 05:42:18 INFO - Return code: 0 05:42:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:42:18 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 0x106d96490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc18bcd09d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, '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.YiETun6bOR/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.wwXwIYbfzC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:18 INFO - 'HOME': '/Users/cltbld', 05:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:18 INFO - 'LOGNAME': 'cltbld', 05:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:18 INFO - 'MOZ_NO_REMOTE': '1', 05:42:18 INFO - 'NO_EM_RESTART': '1', 05:42:18 INFO - 'PAGER': '/bin/cat', 05:42:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:18 INFO - 'PWD': '/builds/slave/test', 05:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:18 INFO - 'SHELL': '/bin/bash', 05:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:42:18 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:18 INFO - 'USER': 'cltbld', 05:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:18 INFO - 'XPC_FLAGS': '0x0', 05:42:18 INFO - 'XPC_SERVICE_NAME': '0', 05:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:42:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:42:19 INFO - Cleaning up... 05:42:19 INFO - Return code: 0 05:42:19 INFO - Running post-action listener: _resource_record_post_action 05:42:19 INFO - Running post-action listener: _start_resource_monitoring 05:42:19 INFO - Starting resource monitoring. 05:42:19 INFO - [mozharness: 2016-11-05 12:42:19.122406Z] Finished create-virtualenv step (success) 05:42:19 INFO - [mozharness: 2016-11-05 12:42:19.123350Z] Running install step. 05:42:19 INFO - Running pre-action listener: _resource_record_pre_action 05:42:19 INFO - Running main action method: install 05:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:42:19 INFO - Reading from file tmpfile_stdout 05:42:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:42:19 INFO - Reading from file tmpfile_stdout 05:42:19 INFO - Output received: 05:42:19 INFO - Usage: mozinstall [options] installer 05:42:19 INFO - Options: 05:42:19 INFO - -h, --help show this help message and exit 05:42:19 INFO - -d DEST, --destination=DEST 05:42:19 INFO - Directory to install application into. [default: 05:42:19 INFO - "/builds/slave/test"] 05:42:19 INFO - --app=APP Application being installed. [default: firefox] 05:42:19 INFO - mkdir: /builds/slave/test/build/application 05:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:42:53 INFO - Reading from file tmpfile_stdout 05:42:53 INFO - Output received: 05:42:53 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:42:53 INFO - Running post-action listener: _resource_record_post_action 05:42:53 INFO - [mozharness: 2016-11-05 12:42:53.563191Z] Finished install step (success) 05:42:53 INFO - [mozharness: 2016-11-05 12:42:53.563317Z] Running run-tests step. 05:42:53 INFO - Running pre-action listener: _resource_record_pre_action 05:42:53 INFO - Running main action method: run_tests 05:42:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:53 INFO - 2016-11-05 05:42:53.577 screenresolution[6415:113561] starting screenresolution argv=screenresolution get 05:42:53 INFO - 2016-11-05 05:42:53.580 screenresolution[6415:113561] Display 0: 1600x1200x32@60 05:42:53 INFO - 2016-11-05 05:42:53.586 screenresolution[6416:113563] starting screenresolution argv=screenresolution list 05:42:53 INFO - Available Modes on Display 0 05:42:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:53 INFO - Intel Iris: 05:42:53 INFO - Chipset Model: Intel Iris 05:42:53 INFO - Type: GPU 05:42:53 INFO - Bus: Built-In 05:42:53 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:53 INFO - Vendor: Intel (0x8086) 05:42:53 INFO - Device ID: 0x0a2e 05:42:53 INFO - Revision ID: 0x0009 05:42:53 INFO - Displays: 05:42:53 INFO - Display: 05:42:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:53 INFO - Display Serial Number: 1600x1200 60 05:42:53 INFO - Main Display: Yes 05:42:53 INFO - Mirror: Off 05:42:53 INFO - Online: Yes 05:42:53 INFO - Rotation: Supported 05:42:53 INFO - Return code: 0 05:42:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:42:53 INFO - Python 2.7.3 05:42:53 INFO - Return code: 0 05:42:53 INFO - grabbing minidump binary from tooltool 05:42: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')]} 05:42:53 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 0x106baad30>, '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 0x1069d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ba2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:42:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:42:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:42:53 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:42:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:42:53 INFO - Return code: 0 05:42:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:42:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:42:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:42:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:42:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:42:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0297 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:42:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YiETun6bOR/Render', 05:42:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:53 INFO - 'HOME': '/Users/cltbld', 05:42:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:53 INFO - 'LOGNAME': 'cltbld', 05:42:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:42:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:53 INFO - 'MOZ_NO_REMOTE': '1', 05:42:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:42:53 INFO - 'NO_EM_RESTART': '1', 05:42:53 INFO - 'PAGER': '/bin/cat', 05:42:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:53 INFO - 'PWD': '/builds/slave/test', 05:42:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:42:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:53 INFO - 'SHELL': '/bin/bash', 05:42:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wwXwIYbfzC/Listeners', 05:42:53 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 05:42:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:53 INFO - 'USER': 'cltbld', 05:42:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:53 INFO - 'XPC_FLAGS': '0x0', 05:42:53 INFO - 'XPC_SERVICE_NAME': '0', 05:42:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:42:54 INFO - mozversion application_buildid: 20161105030211 05:42:54 INFO - mozversion application_changeset: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd 05:42:54 INFO - mozversion application_display_name: Nightly 05:42:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:42:54 INFO - mozversion application_name: Firefox 05:42:54 INFO - mozversion application_remotingname: firefox 05:42:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:42:54 INFO - mozversion application_vendor: Mozilla 05:42:54 INFO - mozversion application_version: 52.0a1 05:42:54 INFO - mozversion platform_buildid: 20161105030211 05:42:54 INFO - mozversion platform_changeset: a7c654513f2ffd9d9ef38fa2bf512b9e8dae3cdd 05:42:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:42:54 INFO - mozversion platform_version: 52.0a1 05:42:54 INFO - using testdate: 1478349774 05:42:54 INFO - actual date: 1478349774 05:42:54 INFO - starting webserver on 'localhost:57458' 05:42:54 INFO - SUITE-START | Running 2 tests 05:42:54 INFO - TEST-START | dromaeo_css 05:42:54 INFO - operating with platform_type : mac_ 05:42:54 INFO - Initialising browser for dromaeo_css test... 05:42:54 INFO - TEST-INFO | started process 6426 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpj3IwAM/profile http://localhost:57458/getInfo.html) 05:42:57 INFO - PROCESS | 6426 | 2016-11-05 05:42:57.132 plugin-container[6429:113715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9443, name = 'com.apple.tsm.portname' 05:42:57 INFO - PROCESS | 6426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:57 INFO - PROCESS | 6426 | 2016-11-05 05:42:57.132 plugin-container[6429:113715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 05:42:57 INFO - PROCESS | 6426 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:57 INFO - PROCESS | 6426 | 2016-11-05 05:42:57.133 plugin-container[6429:113715] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:57 INFO - PROCESS | 6426 | __metrics Screen width/height:1600/1200 05:42:57 INFO - PROCESS | 6426 | colorDepth:24 05:42:57 INFO - PROCESS | 6426 | Browser inner width/height: 1024/689 05:42:57 INFO - PROCESS | 6426 | __metrics 05:42:57 ERROR - PROCESS | 6426 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 05:42:58 INFO - TEST-INFO | 6426: exit 0 05:42:58 INFO - Browser initialized. 05:42:58 INFO - Running cycle 1/1 for dromaeo_css test... 05:42:58 INFO - TEST-INFO | started process 6431 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpj3IwAM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:43:00 INFO - PROCESS | 6431 | 2016-11-05 05:43:00.647 plugin-container[6432:113841] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 05:43:00 INFO - PROCESS | 6431 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:00 INFO - PROCESS | 6431 | 2016-11-05 05:43:00.647 plugin-container[6432:113841] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:43:00 INFO - PROCESS | 6431 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:43:00 INFO - PROCESS | 6431 | 2016-11-05 05:43:00.647 plugin-container[6432:113841] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:43:10 INFO - PROCESS | 6431 | RSS: Main: 238927872 05:43:10 INFO - PROCESS | 6431 | 05:43:59 INFO - PROCESS | 6431 | 1478349839989 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:46:13 INFO - PROCESS | 6431 | Cycle 1(1): loaded http://localhost:57458/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57458/tests/dromaeo/cssquery-ext.html) 05:46:15 INFO - PROCESS | 6431 | RSS: Main: 252088320 05:46:15 INFO - PROCESS | 6431 | 05:49:17 INFO - PROCESS | 6431 | Cycle 1(1): loaded http://localhost:57458/tests/dromaeo/cssquery-ext.html (next: http://localhost:57458/tests/dromaeo/cssquery-jquery.html) 05:49:18 INFO - PROCESS | 6431 | RSS: Main: 263405568 05:49:18 INFO - PROCESS | 6431 | 05:52:20 INFO - PROCESS | 6431 | Cycle 1(1): loaded http://localhost:57458/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57458/tests/dromaeo/cssquery-mootools.html) 05:52:20 INFO - PROCESS | 6431 | RSS: Main: 266711040 05:52:20 INFO - PROCESS | 6431 | 05:55:22 INFO - PROCESS | 6431 | Cycle 1(1): loaded http://localhost:57458/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57458/tests/dromaeo/cssquery-prototype.html) 05:55:23 INFO - PROCESS | 6431 | RSS: Main: 269914112 05:55:23 INFO - PROCESS | 6431 | 05:58:26 INFO - PROCESS | 6431 | Cycle 1(1): loaded http://localhost:57458/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57458/tests/dromaeo/cssquery-yui.html) 05:58:26 INFO - PROCESS | 6431 | RSS: Main: 274276352 05:58:26 INFO - PROCESS | 6431 | 06:01:29 INFO - PROCESS | 6431 | Cycle 1(1): loaded http://localhost:57458/tests/dromaeo/cssquery-yui.html (next: http://localhost:57458/tests/dromaeo/cssquery-dojo.html) 06:01:30 INFO - PROCESS | 6431 | RSS: Main: 276250624 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | __start_tp_report 06:01:30 INFO - PROCESS | 6431 | _x_x_mozilla_page_load 06:01:30 INFO - PROCESS | 6431 | _x_x_mozilla_page_load_details 06:01:30 INFO - PROCESS | 6431 | |i|pagename|runs| 06:01:30 INFO - PROCESS | 6431 | |0;dojo.html;3272.3128143089953;3353.2455197580534;3363.596368435788;3373.0218236711353;3395.3718562066006;4879.975600121995;4881.487443818392;4930.802767889285;5655.802046928358;5683.2895888014;4472.7987240574175;5674.09214525676;5679.4036626154275;5705.315362156537;5727.513161381277;5153.330067091273;5209.2707021016895;5243.449437809032;5258.368995720519;5262.868428289305;4033.939490907624;5222.373315202181;5262.158054711247;5278.445763194867;5289.233061206142;5254.973725131369;5279.762410691509;5285.154375299952;5291.100512912795;5297.523222909937;5265.842024739245;5285.814996475144;5287.867803304929;5290.735463226862;5302.867428314285;4283.828646854122;5142.794288228466;5230.660007099565;5243;5249.343832020997;5765.423457654243;5770.913436298459;5802.390748971339;5811.622244554091;5883.79406720767;6094.055421409688;6255.437010669016;6262.906056409158;6290.874182516324;6297;6133.457080270857;6213.56505044647;6244.687765611701;6280.560360774748;6291.874162516724;5916.083007133901;5916.556258280646;5942.168096466498;5962.34414214436;5967.582269241155;6289.7169627366875;6324.430801227912;6330.873382532323;6346.904796428057;6353.872922541524;6094.421030002142;6124.472479235491;6134;6222.408871157233;6234.220722409699;6137.846553836101;6177.382261773787;6221.377862213742;6239.656818874914;6261.060840873905;6261.686915654199;6263.154474146023;6265.592736472115;6276.278228003746;6279.277883043417;6162.5378096642935;6165.969170154121;6225.750969961241;6256.749730010749;6311.495080393557;4955.4796746341835;4977.751112444364;4978.751062446863;4984.077945580079;4990.3762029746285;4488.483824360175;4491.775411229425;4497.347884556786;4499.280115181556;4516.23224051912;5282.788688452496;5711.485966263056;5785.479306862402;5788.9710551447815;5790.247267855152;6244.594101383395;6261.24865016201;6278.246610406781;6278.4035516625845;6284.685765711696;5828.475437210672;5907.764504695301;5915.467607915309;5951.434613711691;5985.22192115031;5350.705711185922;5384.219288203189;5417.3770016447825;5451.563874889999;5462.3445186577865;5487.368952570346;6103.816885493442;6268.686565671789;6281.59169653971;6315.27374351937;6242.931238073389;6280.623162610258;6297.96851015742;6303.842403939755;6320.241571011508;6187;6201.782937597162;6228.875422491616;6242.687865606792;6259.65571893532;6202.162708034538;6215.06773983888;6271.310155882758;6290.0879372491645;6293.653849038347;6250.781222657185;6254.562180647493;6274.937250627618;6286.779962701284;6299.874002520016;4303.612674859278;4919.508049195052;4977.726225057657;5157.200633901752;5864.003119469615;5945.970270148621;6129.222525467564;6159.23009623797;6161;6165.290991536031;5026.4973502649445;5143.794248230029;6037.818865434044;6077.604955677867;6077.787277445268;6021.307549631849;6031.4270144336215;6156.984097623842;6165.599236049642;6166.506679465721;5904.937111319832;5977.22296101504;6019.488343490677;6037.426444487766;6100.0849872519475 06:01:30 INFO - PROCESS | 6431 | |1;ext.html;445.5188396531746;466.9719816810992;522.5114517925738;529.790732660599;550.8047536845046;34187.82906085469;39515.80242098789;39518.2096358073;40495.19009619806;41460;33977.49033764489;40720.18559628806;40741.796291018574;40881.79559102208;40928;9248.630054797817;9394.62421503139;9431.622735090588;9443.197328227108;9466.90533094667;9369.250459963187;9387.155156035955;9418.199453046465;9430.717078487656;9444.574994125282;9435.85846212304;9445.71662850112;9456.527173641325;9475.81048379032;9495.335326527145;9384.296177786659;9456.054394560557;9465.621375145054;9466.100720431548;9489.240860731194;9157.542122893828;9185.448873067637;9218.078192180796;9305.162535371848;9307.488088155216;34751.5248475153;38465.61534384648;38544.80727596344;38804.61195388067;38815;3332.6334103248623;3438.710483568662;3822.675072618803;3939.172773717523;3950.071733142709;29505.852470737722;29795.851020744758;29867.850660746775;29996.700032999608;30734.23164420885;3222.146131275214;3251.4960181171955;3258.218027673341;3299.2741596848655;3370.477575975703;471350;481428;483336;485805.99999999645;486306;55205.755453231875;55345.72327138339;55401.89196216053;55477.1678424824;55529;57284.71357643185;58052.419475805546;58109.70945145332;58229.70885145632;58384.4161558379;15337.003094798802;15470.613234669223;15552.455664051704;15573.454929077428;15582.532524024298;9041.914970203617;11437.656870293904;12035.458404371824;12105.213161144497;12211;14569.635759106108;15070.698586028218;15233.466828660943;15239;15439.228038598025;3146.9685303147135;5146.845594632092;5867.53311672082;6088.969555152285;6115.541334399938;848.4866655673352;891.7159290621485;916.8258030974093;921.5852866210232;1120.3725913488472;24368.87815560911;28688.569671454945;29198.124056278346;29827.85086074556;30005.099847004625;993.8310487217046;1019.4494972714799;1110.1174566219875;1128.7178205448638;1168.9006434452997;13187.868121318856;13796.379162937492;15169.317380717674;15378;15615.921920390552;662462;665053;670785;671533;673706;55264.17103743447;55338;55338;55454.72272638611;55828;1299.64259828546;1311.7573248948975;1347.7439286535528;1352.1819299323768;1352.7024054707751;22852;28843.42313153767;28860.134195974155;28943.276418089717;29490.85254573799;47827.804304892656;48036.27945580749;48065.75967120142;48090.5190948086;48109.5189048105;596346.0182699233;601249;604675;606370;607084;25522.744772552778;25870.35324116837;25886.482270354863;25916.740832592186;25951.96192152368;44044;44487.77756111328;44687.55312446964;45819;45885;34196.48705269473;36227.27545449129;36693.63306367009;37396.81301593475;39688.80155599204;36688;37018.259634806614;37383.43924841075;38412.61587384091;38759.806200968815;35915;36489.81755091208;37343.253134937695;37593;37741.4338784924;32042.679573204903;33574.49638255477;33688.115223292974;33827.66172338343;33896.15259618529;19758.703619445434;24657.876710616332;24815.751842481342;24823.50352992966;25394.921015797285 06:01:30 INFO - PROCESS | 6431 | |2;jquery.html;359.93161299353125;368.90961714379984;374.3561074951084;379.5653976197256;397.0827388732029;18659.760828700557;18866.962317072557;18888;19624.90187549062;19832.603347933033;18483.2606695732;18513.81486185141;19107.522311942208;19117.522061948424;19126.617467650674;4198.37024446334;5053.140966035774;5286.154215325549;5296.788128474856;5308.177232528964;4969.378827646544;5304.151335786275;5773.364929857712;5855.004649209644;5863.091220860773;5495.972520137394;5731.742071606767;5794.768209271645;5812.302523697141;5826.883462330751;5752.65484070955;5755.338136114358;5882.735276912549;5896.882062358772;5909.231799866033;5477.370102438191;5679.687617181054;5689.971550142222;5704.372519022904;5723.341815691208;18750;23203.651945220834;23862;23956.880215598983;24376.268711938672;1528.8624023837908;2232.7320721513524;2258.9096436142536;2308.3305841306;2611.138324352959;9645.324827262095;9666.613335466573;10206.591736330538;12653.367331633473;14224.715505689932;1791.3282519055354;1847.4088291746646;1880.172724001435;1880.8871467711845;1893.5455490682275;123530;126197.99999999908;126938;127912.72087279102;129255;9592.84885813693;10895.746989096197;11174.050205732441;11267.211295209421;11509.561304836896;11637.88362116385;14132;14259.857401426061;14262.144271343544;14285;7947.682092716343;8205.876911846328;8227.095019547845;8306.252437280673;8368;6984.860302794017;7105.609191494517;8004.759857204293;8057.31512821405;8149.714759983491;4388.253996820548;4415.470143582791;4485.798139083684;4488.4165058542185;4588.839390621312;1614.612493001671;1865.8729048884284;1913.1199648162;1921.4619906425944;1924.4419118455492;827.5117680568374;846.8475674378672;852.6205838401852;872.8952525696957;933.6312156698066;15495.380184792708;19608.411747647595;19905.90517521521;19989.500262493555;20061.896595687376;760.5056713136505;888.2228050455851;897.7351681254046;966.2077096780713;976.6337623391229;7592;7904;8874.757533945252;10052.095311422006;10171.220036493583;486295;520857;559758;568586;595746;111029.88970110357;118631.40684296524;122248.38875805565;132152;132444.3377783105;903.5346796399859;967.2310513142069;1001.3040936549029;1014.5840205515703;1016.1058268723476;16922.15389230509;18159.909200453912;18187.636247275244;18354.90822545879;19025.52436189051;41536.79231603823;43035.78482107569;50634.98730025305;55363.72318138384;56872.715636421555;72097.6395118021;73325.26674733184;74618.62690686512;77695;77802.61098694697;7779.611019448892;7943.086545047394;7984.640691169027;8019.1579884111325;8116.918830811616;10229.948850255701;11244.65064192302;12177.147599668293;12399.318037508023;12413.069019823368;4754.381930349033;8682.91317086821;10714.357138571399;10757.181200673647;10794;8257.545834979133;8750.299976002032;10535;10677.412742299248;10857.511411986321;10525.684229473129;10791.946040269748;10798.622048228275;10939.343639381663;11161.776764464828;7565.4325925556905;7780.455368124403;7843.921560784547;7848.882266765889;7926.722564710208;9749.658761943298;10436.582536698446;10670.093042091508;10677.89322106769;10744.83882741775 06:01:30 INFO - PROCESS | 6431 | |3;mootools.html;1296.3906963727047;1441.2217402602594;1595.298068849706;1771.982280177198;1779.857611391088;7541.057367829021;7829.334506566935;7969.203079692028;8465.703700370488;8487.023992240895;7986.001749781278;8282.58587070647;8301.501909885395;8371.414001019932;8396.580170991456;2269.6255117905566;2303.3205204464725;2318.3740390094663;2318.686977258074;2326.139328448468;1966.4788830959808;2311.3874823171873;2335.5679199348174;2347.272345572869;2357.092519380034;2288.416453804286;2291.702078729763;2293.0713061210236;2317.5017371265176;2327.9534409311805;2276.8520046196945;2281.863088214704;2299.0688771047753;2305.4351683837485;2308.8152947764224;1927.4603111128906;2309.6304591265484;2321.8258630602772;2324.825638077151;2338.497223097025;8330.750077497685;8350.707725229588;8394.286485648727;8405.663773449054;8410.201030902055;5046.798128074873;5174;5217.843464696065;5231.633785634969;5247.737613119329;3025.774561302676;3265.2000259936467;3361.344537815127;3640.3447379471686;3641.326354624403;1042.1818872185374;1057.7461409261798;1079.6976846482996;1102.8841971593029;1102.9889701102966;10742;10944.835827462595;10981.011708946233;10985.725356866062;11011.669649910515;4386.473623165177;4521.321801729767;4602.171609110393;4608.654350923694;4636.79134438951;9344.579493922789;9388.483633400228;9397.436153830791;9399.71800845962;9409.858852117224;4272.829086836554;4627.676062675648;4743.952560474351;5082.567981721595;5111.853021730882;3331.666833316649;3644.726645501598;4013.337799263147;4222.19778242133;4399.14216727735;3214.3571285742946;3555.591107022674;3722.683571896419;3837.731358804857;3855.5951625078965;1264.576366917093;1267.8732126787247;1269.2194300505123;1279.9680007999689;1282.7498637765732;752.6725874244721;803.2088392932957;814.4705941138308;883.9348584955113;892.8749975003504;5603.3836278009385;5647.407022262603;5806.44838740319;5856.912146317809;5913.201717768132;424.21731904635897;427.5083653798157;431.0172806002257;432.95441508756;435.912817436514;3769.679577235911;3800.410936304877;3865.2849222893856;3890.105275786585;3971.324874771296;8275.379346549033;10038.548265328078;10135.341202821794;10143;10167.23745719074;9871;9938.552765125587;10041.297109202433;10143.391396516232;10192.439415832052;7763.9611801941755;7813.609319534001;7893.289603935674;7903.56530390834;7905.841883162305;5456.154296084113;5586.469285418041;5630.493255607015;5649.7740090397565;5676.7445464955;9387.342885997914;9403.4828084456;9436;9436.961934187097;9449.763755906157;8935.508547029976;9302.16280534755;9303.348765586614;9484.715458536255;9485.146336829719;4885.95114048855;6759.121314229119;6898.344657257552;7066.7173313066905;7222.458315626244;7741.380689544711;7989;7995.240452157036;8000.719974801087;8030.95984520097;7061;7129.001939728546;7129.964350178216;7170.605616691132;7280.089988751406;6832.316768323128;6948.409385202112;6969.765061293705;7041.436685065285;7158.355747982707;6624.337566243337;6655;6712.563683360566;6716.093327400738;6776.525643205126;4844.975775121102;4880.219164933594;4888.608911287018;4925.285833554186;4953.603711703127;4822.68652537584;4842.95157048425;4897.975510122426;4961.255811628284;4985.825496107619 06:01:30 INFO - PROCESS | 6431 | |4;prototype.html;255.95648739714247;264.6850248204637;292.3627684964201;297.12052325117645;308.4755914944595;5273.016998936007;5440.619156659035;5473.536908391225;5515.117581187011;5550.972245138779;5232.921506177401;5314.388845282793;5384.1923711443305;5388.7305634718305;5405.972970135143;3789.2042671038976;3992.482856514535;4092.795360231984;4149.315362965122;4166.937495937564;3821.331267028273;4067.925358067392;4097.73364731293;4164.938355514009;4194.580541945811;3414.2830005698834;4064.9390259146135;4086.8978275543054;4089.3661482470266;4090.670532077072;3233.8383080845865;3484.0837539353256;4054.3918412238104;4056.3307054336146;4078.9796051019557;2907.331313797817;3105.7049580289836;3539.07983924179;3540.026492714524;3560.430331147004;5029.723365214912;5250.343707036621;5274.947250527484;5309.548688361494;5411.810586629488;4494.887402920622;4648.325992731069;4659.136345461842;4662.927526668851;4692.770933806449;4602.838900638495;4825.324454576362;5371.4897084776885;5715.999700052515;5747.367789543188;3689.022409061574;3799.618339677689;3852.5184351956004;3854.9421758673643;3888.863889763845;5481.506664400184;5817.621854579481;5961.701914904237;6007.429294217042;6008.519318454513;3395.5246265522846;3923.6272554107313;3927.7446965947124;3943.684505239574;3951.8221680023894;3388.745844061705;3788.659020688096;3866.845326186921;3994.2639292085014;3999.7800120993043;4613;4622.260438329851;4628.95371046292;4636.6058884995155;4644.117617652635;5718.771249149988;5739.253896993364;5743.052396354638;5765.434381700187;5777.740001699934;1201.024315001817;1210.6791700199453;1229.7171650520431;1255.9575552291576;1257.5849969510223;840.9915900841036;854.9185280620021;859.7119964811733;865.9090795466511;867.6876324523166;4403.405540252025;4709.764511774398;4836.274558816206;5048.056498248201;5145.819896303611;4498.640108791355;4624.791884365211;5139.434662187156;5477.561795056386;5539.446055394509;4751.501092385319;4972.279019542146;4986.825461108844;5015.623828212899;5054.898902021939;2646.6427032350384;2848.7008864068976;2871.9282017949718;2895.7683385329124;2934.413117376533;5075.746212689351;5133.8716532087;5711.543076553866;5718.342390625131;5718.771249150071;4659.930101048487;5453.191606322747;5658.74535645897;5724.969505489052;5752.309722833287;4752.500987396343;5725.766950889157;5734.4838964492565;5735.36910939788;5748.160588611604;5100.817329789537;5261.736913154404;5487.945120548743;5495.230667706604;5513.807016754555;5128.3076784633595;5391.649542779707;5445.740017892688;5483.8209784896435;5505.6696598204235;5601.619311792553;5629.240052592848;5645.40723224072;5660.943390566042;5660.943390566042;3287.375398674244;3611.5846677631357;3636.127394391943;3668.79821609814;3682;5067.403767813127;5482.793785367213;5545.336399080179;5588.568972496814;5611.747471363717;5503.532199763065;5596.804111856066;5682.261306030189;5717.42825717425;5724.227229323988;4788.4253889533475;5557.749901254372;5649.435056494318;5659.349174844946;5712.228849105318;4075.0219947211863;4356.542563030963;5451.046066938191;5588.580856435865;5656.943430565641;5288;5575.080111781511;5611.410801865909;5732.337622089714;5750.7987220447085;4524.642607217887;5619.690916999604;5619.8876022480135;5623.3814280428305;5638.689872057076 06:01:30 INFO - PROCESS | 6431 | |5;yui.html;616.6084536319437;701.4388489208635;738.1544147312109;768.6003278295283;780.9375249980001;14081.225532595703;14769.187694676788;14816.03695759775;14838.183899885515;14883.479078232263;12970.351482425864;13313.733725325512;13410.46358145673;13480.66298342542;13512.594622161327;815.042325267811;826.8263664630434;833.8499070167368;834.7871292820322;834.7871292820322;664.1200409457464;686.5194363945234;804.6660635836121;830.2486249943811;833.0877688930642;824.1511243419287;852.1180578101666;858.047567200407;858.6222062292602;858.8539948208819;853.8847255620474;857.0187135729586;858.2790456016913;859.0636206534875;860.4579115157474;637.139861187398;646.7574659502686;647.2136354329513;648.1832890557884;648.3516483516484;13408.664783380396;13717.17696938187;13773.173609583457;13849.584512464642;13864.722705545833;12708.421392874034;13128.540501082416;13136.6715832104;13154.86845131546;13363.732725345535;10919.727006824813;11122;11196.832047519294;11314.264572802742;11507.539698412054;3587.4080776671817;3635.218428037958;3671.504346913186;3762.473253744478;3765.4728338032696;88909;89109;89239;90814.5459272706;92535.53732231363;10152.441615711059;10192.847107293397;10209.74475638115;10315.638952636771;10667.039966403061;19687.40937771869;19718.507037323896;19726.802731972784;20138.496537586383;20157.19371225164;7355.374793142642;7413.555186688816;7420.072490938633;7580.787074068123;7599.886001709979;4059.512858457004;4115.9176816463505;4130.153318569686;4203.498119983725;4283.464566929134;2959.926001849971;3318.7179089777637;3406.880759173417;3415.487676848493;3430.708389786896;638.9680515974183;643.7038962077497;647.1974751308438;647.5078940005629;654.7904670505393;566.6033776356567;609.3668967167944;634.5875181132209;672.5729161982078;677.190757045336;8411.45325553837;8780.253678437277;8905.109489051043;9040.141186587263;9151.084891510796;625.6027422586599;631.1511017681212;653.947684185264;666;671.1945665201778;3709.5919448860604;4115.176964607091;4338.370936214231;4424.655072579502;4464.352668863062;73985;76605;96268;104819;109001;60965;63666.36333636697;87206;91373.08626913778;93054;41824;58384;58592.70703646455;76886;86378;9178.632854685738;9375.437473751597;9429.434233945985;9444.763880903032;9464.479453630116;45131.09737805291;45942.62172134841;46163.30755038744;46478.76760616175;47435.76282118567;65071.674641628386;66940.6652966732;67843.66078169577;68609.65695171691;69385.65307173431;11995;12041.75916481648;12101.576444824388;12326.198797078161;12370;12175.33035683046;13255.806977372084;13767.449302027806;14027.789583156466;14337.498187563386;13025.739485210053;13216.405261763051;13315.467381305027;13378.197308161542;13576.117552359065;12701.36493175319;12705.428255728699;13430.19418834873;13461.192328460324;13491.392887320291;10912.672619821418;12778.872211277769;12814.05276786363;13055.543055992996;13506.459741610619;10676.73308167271;10790.622328218475;10915.781684366428;10974.067204287725;11225.270357427067;9349.906500935176;9605.759856003655;9717.271204659537;10160.288779785344;10849.566017359217 06:01:30 INFO - PROCESS | 6431 | __end_tp_report 06:01:30 INFO - PROCESS | 6431 | __start_cc_report 06:01:30 INFO - PROCESS | 6431 | _x_x_mozilla_cycle_collect,366 06:01:30 INFO - PROCESS | 6431 | __end_cc_report 06:01:30 INFO - PROCESS | 6431 | __startTimestamp1478350890024__endTimestamp 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | ------- Summary: start ------- 06:01:30 INFO - PROCESS | 6431 | Number of tests: 6 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | [#0] dojo.html Cycles:165 Average:5699.67 Median:5962.34 stddev:688.38 (11.5%) stddev-sans-first:663.63 06:01:30 INFO - PROCESS | 6431 | Values: 3272.3 3353.2 3363.6 3373.0 3395.4 4880.0 4881.5 4930.8 5655.8 5683.3 4472.8 5674.1 5679.4 5705.3 5727.5 5153.3 5209.3 5243.4 5258.4 5262.9 4033.9 5222.4 5262.2 5278.4 5289.2 5255.0 5279.8 5285.2 5291.1 5297.5 5265.8 5285.8 5287.9 5290.7 5302.9 4283.8 5142.8 5230.7 5243.0 5249.3 5765.4 5770.9 5802.4 5811.6 5883.8 6094.1 6255.4 6262.9 6290.9 6297.0 6133.5 6213.6 6244.7 6280.6 6291.9 5916.1 5916.6 5942.2 5962.3 5967.6 6289.7 6324.4 6330.9 6346.9 6353.9 6094.4 6124.5 6134.0 6222.4 6234.2 6137.8 6177.4 6221.4 6239.7 6261.1 6261.7 6263.2 6265.6 6276.3 6279.3 6162.5 6166.0 6225.8 6256.7 6311.5 4955.5 4977.8 4978.8 4984.1 4990.4 4488.5 4491.8 4497.3 4499.3 4516.2 5282.8 5711.5 5785.5 5789.0 5790.2 6244.6 6261.2 6278.2 6278.4 6284.7 5828.5 5907.8 5915.5 5951.4 5985.2 5350.7 5384.2 5417.4 5451.6 5462.3 5487.4 6103.8 6268.7 6281.6 6315.3 6242.9 6280.6 6298.0 6303.8 6320.2 6187.0 6201.8 6228.9 6242.7 6259.7 6202.2 6215.1 6271.3 6290.1 6293.7 6250.8 6254.6 6274.9 6286.8 6299.9 4303.6 4919.5 4977.7 5157.2 5864.0 5946.0 6129.2 6159.2 6161.0 6165.3 5026.5 5143.8 6037.8 6077.6 6077.8 6021.3 6031.4 6157.0 6165.6 6166.5 5904.9 5977.2 6019.5 6037.4 6100.1 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | [#1] ext.html Cycles:180 Average:70339.58 Median:21732.94 stddev:157986.98 (726.9%) stddev-sans-first:158343.02 06:01:30 INFO - PROCESS | 6431 | Values: 445.5 467.0 522.5 529.8 550.8 34187.8 39515.8 39518.2 40495.2 41460.0 33977.5 40720.2 40741.8 40881.8 40928.0 9248.6 9394.6 9431.6 9443.2 9466.9 9369.3 9387.2 9418.2 9430.7 9444.6 9435.9 9445.7 9456.5 9475.8 9495.3 9384.3 9456.1 9465.6 9466.1 9489.2 9157.5 9185.4 9218.1 9305.2 9307.5 34751.5 38465.6 38544.8 38804.6 38815.0 3332.6 3438.7 3822.7 3939.2 3950.1 29505.9 29795.9 29867.9 29996.7 30734.2 3222.1 3251.5 3258.2 3299.3 3370.5 471350.0 481428.0 483336.0 485806.0 486306.0 55205.8 55345.7 55401.9 55477.2 55529.0 57284.7 58052.4 58109.7 58229.7 58384.4 15337.0 15470.6 15552.5 15573.5 15582.5 9041.9 11437.7 12035.5 12105.2 12211.0 14569.6 15070.7 15233.5 15239.0 15439.2 3147.0 5146.8 5867.5 6089.0 6115.5 848.5 891.7 916.8 921.6 1120.4 24368.9 28688.6 29198.1 29827.9 30005.1 993.8 1019.4 1110.1 1128.7 1168.9 13187.9 13796.4 15169.3 15378.0 15615.9 662462.0 665053.0 670785.0 671533.0 673706.0 55264.2 55338.0 55338.0 55454.7 55828.0 1299.6 1311.8 1347.7 1352.2 1352.7 22852.0 28843.4 28860.1 28943.3 29490.9 47827.8 48036.3 48065.8 48090.5 48109.5 596346.0 601249.0 604675.0 606370.0 607084.0 25522.7 25870.4 25886.5 25916.7 25952.0 44044.0 44487.8 44687.6 45819.0 45885.0 34196.5 36227.3 36693.6 37396.8 39688.8 36688.0 37018.3 37383.4 38412.6 38759.8 35915.0 36489.8 37343.3 37593.0 37741.4 32042.7 33574.5 33688.1 33827.7 33896.2 19758.7 24657.9 24815.8 24823.5 25394.9 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | [#2] jquery.html Cycles:180 Average:33016.92 Median:388.32 stddev:92081.75 (23712.6%) stddev-sans-first:92307.42 06:01:30 INFO - PROCESS | 6431 | Values: 359.9 368.9 374.4 379.6 397.1 18659.8 18867.0 18888.0 19624.9 19832.6 18483.3 18513.8 19107.5 19117.5 19126.6 4198.4 5053.1 5286.2 5296.8 5308.2 4969.4 5304.2 5773.4 5855.0 5863.1 5496.0 5731.7 5794.8 5812.3 5826.9 5752.7 5755.3 5882.7 5896.9 5909.2 5477.4 5679.7 5690.0 5704.4 5723.3 18750.0 23203.7 23862.0 23956.9 24376.3 1528.9 2232.7 2258.9 2308.3 2611.1 9645.3 9666.6 10206.6 12653.4 14224.7 1791.3 1847.4 1880.2 1880.9 1893.5 123530.0 126198.0 126938.0 127912.7 129255.0 9592.8 10895.7 11174.1 11267.2 11509.6 11637.9 14132.0 14259.9 14262.1 14285.0 7947.7 8205.9 8227.1 8306.3 8368.0 6984.9 7105.6 8004.8 8057.3 8149.7 4388.3 4415.5 4485.8 4488.4 4588.8 1614.6 1865.9 1913.1 1921.5 1924.4 827.5 846.8 852.6 872.9 933.6 15495.4 19608.4 19905.9 19989.5 20061.9 760.5 888.2 897.7 966.2 976.6 7592.0 7904.0 8874.8 10052.1 10171.2 486295.0 520857.0 559758.0 568586.0 595746.0 111029.9 118631.4 122248.4 132152.0 132444.3 903.5 967.2 1001.3 1014.6 1016.1 16922.2 18159.9 18187.6 18354.9 19025.5 41536.8 43035.8 50635.0 55363.7 56872.7 72097.6 73325.3 74618.6 77695.0 77802.6 7779.6 7943.1 7984.6 8019.2 8116.9 10229.9 11244.7 12177.1 12399.3 12413.1 4754.4 8682.9 10714.4 10757.2 10794.0 8257.5 8750.3 10535.0 10677.4 10857.5 10525.7 10791.9 10798.6 10939.3 11161.8 7565.4 7780.5 7843.9 7848.9 7926.7 9749.7 10436.6 10670.1 10677.9 10744.8 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | [#3] mootools.html Cycles:180 Average:5384.14 Median:4893.29 stddev:3007.50 (61.5%) stddev-sans-first:3000.24 06:01:30 INFO - PROCESS | 6431 | Values: 1296.4 1441.2 1595.3 1772.0 1779.9 7541.1 7829.3 7969.2 8465.7 8487.0 7986.0 8282.6 8301.5 8371.4 8396.6 2269.6 2303.3 2318.4 2318.7 2326.1 1966.5 2311.4 2335.6 2347.3 2357.1 2288.4 2291.7 2293.1 2317.5 2328.0 2276.9 2281.9 2299.1 2305.4 2308.8 1927.5 2309.6 2321.8 2324.8 2338.5 8330.8 8350.7 8394.3 8405.7 8410.2 5046.8 5174.0 5217.8 5231.6 5247.7 3025.8 3265.2 3361.3 3640.3 3641.3 1042.2 1057.7 1079.7 1102.9 1103.0 10742.0 10944.8 10981.0 10985.7 11011.7 4386.5 4521.3 4602.2 4608.7 4636.8 9344.6 9388.5 9397.4 9399.7 9409.9 4272.8 4627.7 4744.0 5082.6 5111.9 3331.7 3644.7 4013.3 4222.2 4399.1 3214.4 3555.6 3722.7 3837.7 3855.6 1264.6 1267.9 1269.2 1280.0 1282.7 752.7 803.2 814.5 883.9 892.9 5603.4 5647.4 5806.4 5856.9 5913.2 424.2 427.5 431.0 433.0 435.9 3769.7 3800.4 3865.3 3890.1 3971.3 8275.4 10038.5 10135.3 10143.0 10167.2 9871.0 9938.6 10041.3 10143.4 10192.4 7764.0 7813.6 7893.3 7903.6 7905.8 5456.2 5586.5 5630.5 5649.8 5676.7 9387.3 9403.5 9436.0 9437.0 9449.8 8935.5 9302.2 9303.3 9484.7 9485.1 4886.0 6759.1 6898.3 7066.7 7222.5 7741.4 7989.0 7995.2 8000.7 8031.0 7061.0 7129.0 7130.0 7170.6 7280.1 6832.3 6948.4 6969.8 7041.4 7158.4 6624.3 6655.0 6712.6 6716.1 6776.5 4845.0 4880.2 4888.6 4925.3 4953.6 4822.7 4843.0 4898.0 4961.3 4985.8 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | [#4] prototype.html Cycles:180 Average:4496.77 Median:5071.57 stddev:1395.00 (27.5%) stddev-sans-first:1362.11 06:01:30 INFO - PROCESS | 6431 | Values: 256.0 264.7 292.4 297.1 308.5 5273.0 5440.6 5473.5 5515.1 5551.0 5232.9 5314.4 5384.2 5388.7 5406.0 3789.2 3992.5 4092.8 4149.3 4166.9 3821.3 4067.9 4097.7 4164.9 4194.6 3414.3 4064.9 4086.9 4089.4 4090.7 3233.8 3484.1 4054.4 4056.3 4079.0 2907.3 3105.7 3539.1 3540.0 3560.4 5029.7 5250.3 5274.9 5309.5 5411.8 4494.9 4648.3 4659.1 4662.9 4692.8 4602.8 4825.3 5371.5 5716.0 5747.4 3689.0 3799.6 3852.5 3854.9 3888.9 5481.5 5817.6 5961.7 6007.4 6008.5 3395.5 3923.6 3927.7 3943.7 3951.8 3388.7 3788.7 3866.8 3994.3 3999.8 4613.0 4622.3 4629.0 4636.6 4644.1 5718.8 5739.3 5743.1 5765.4 5777.7 1201.0 1210.7 1229.7 1256.0 1257.6 841.0 854.9 859.7 865.9 867.7 4403.4 4709.8 4836.3 5048.1 5145.8 4498.6 4624.8 5139.4 5477.6 5539.4 4751.5 4972.3 4986.8 5015.6 5054.9 2646.6 2848.7 2871.9 2895.8 2934.4 5075.7 5133.9 5711.5 5718.3 5718.8 4659.9 5453.2 5658.7 5725.0 5752.3 4752.5 5725.8 5734.5 5735.4 5748.2 5100.8 5261.7 5487.9 5495.2 5513.8 5128.3 5391.6 5445.7 5483.8 5505.7 5601.6 5629.2 5645.4 5660.9 5660.9 3287.4 3611.6 3636.1 3668.8 3682.0 5067.4 5482.8 5545.3 5588.6 5611.7 5503.5 5596.8 5682.3 5717.4 5724.2 4788.4 5557.7 5649.4 5659.3 5712.2 4075.0 4356.5 5451.0 5588.6 5656.9 5288.0 5575.1 5611.4 5732.3 5750.8 4524.6 5619.7 5619.9 5623.4 5638.7 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - PROCESS | 6431 | [#5] yui.html Cycles:180 Average:18589.92 Median:45536.86 stddev:26256.60 (57.7%) stddev-sans-first:26295.58 06:01:30 INFO - PROCESS | 6431 | Values: 616.6 701.4 738.2 768.6 780.9 14081.2 14769.2 14816.0 14838.2 14883.5 12970.4 13313.7 13410.5 13480.7 13512.6 815.0 826.8 833.8 834.8 834.8 664.1 686.5 804.7 830.2 833.1 824.2 852.1 858.0 858.6 858.9 853.9 857.0 858.3 859.1 860.5 637.1 646.8 647.2 648.2 648.4 13408.7 13717.2 13773.2 13849.6 13864.7 12708.4 13128.5 13136.7 13154.9 13363.7 10919.7 11122.0 11196.8 11314.3 11507.5 3587.4 3635.2 3671.5 3762.5 3765.5 88909.0 89109.0 89239.0 90814.5 92535.5 10152.4 10192.8 10209.7 10315.6 10667.0 19687.4 19718.5 19726.8 20138.5 20157.2 7355.4 7413.6 7420.1 7580.8 7599.9 4059.5 4115.9 4130.2 4203.5 4283.5 2959.9 3318.7 3406.9 3415.5 3430.7 639.0 643.7 647.2 647.5 654.8 566.6 609.4 634.6 672.6 677.2 8411.5 8780.3 8905.1 9040.1 9151.1 625.6 631.2 653.9 666.0 671.2 3709.6 4115.2 4338.4 4424.7 4464.4 73985.0 76605.0 96268.0 104819.0 109001.0 60965.0 63666.4 87206.0 91373.1 93054.0 41824.0 58384.0 58592.7 76886.0 86378.0 9178.6 9375.4 9429.4 9444.8 9464.5 45131.1 45942.6 46163.3 46478.8 47435.8 65071.7 66940.7 67843.7 68609.7 69385.7 11995.0 12041.8 12101.6 12326.2 12370.0 12175.3 13255.8 13767.4 14027.8 14337.5 13025.7 13216.4 13315.5 13378.2 13576.1 12701.4 12705.4 13430.2 13461.2 13491.4 10912.7 12778.9 12814.1 13055.5 13506.5 10676.7 10790.6 10915.8 10974.1 11225.3 9349.9 9605.8 9717.3 10160.3 10849.6 06:01:30 INFO - PROCESS | 6431 | -------- Summary: end -------- 06:01:30 INFO - PROCESS | 6431 | 06:01:30 INFO - TEST-INFO | 6431: exit 0 06:01:30 INFO - TEST-OK | dromaeo_css | took 1116639ms 06:01:30 INFO - TEST-START | kraken 06:01:30 INFO - operating with platform_type : mac_ 06:01:30 INFO - Initialising browser for kraken test... 06:01:30 INFO - TEST-INFO | started process 6435 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpVcl3sT/profile http://localhost:57458/getInfo.html) 06:01:33 INFO - PROCESS | 6435 | 2016-11-05 06:01:33.498 plugin-container[6437:115975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8737, name = 'com.apple.tsm.portname' 06:01:33 INFO - PROCESS | 6435 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:33 INFO - PROCESS | 6435 | 2016-11-05 06:01:33.498 plugin-container[6437:115975] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 06:01:33 INFO - PROCESS | 6435 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:33 INFO - PROCESS | 6435 | 2016-11-05 06:01:33.498 plugin-container[6437:115975] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:01:33 INFO - PROCESS | 6435 | __metrics Screen width/height:1600/1200 06:01:33 INFO - PROCESS | 6435 | colorDepth:24 06:01:33 INFO - PROCESS | 6435 | Browser inner width/height: 1024/689 06:01:33 INFO - PROCESS | 6435 | __metrics 06:01:33 ERROR - PROCESS | 6435 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 06:01:34 INFO - TEST-INFO | 6435: exit 0 06:01:34 INFO - Browser initialized. 06:01:34 INFO - Running cycle 1/1 for kraken test... 06:01:34 INFO - TEST-INFO | started process 6438 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpVcl3sT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:01:36 INFO - PROCESS | 6438 | 2016-11-05 06:01:36.968 plugin-container[6439:116086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 06:01:36 INFO - PROCESS | 6438 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:36 INFO - PROCESS | 6438 | 2016-11-05 06:01:36.968 plugin-container[6439:116086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 06:01:36 INFO - PROCESS | 6438 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:01:36 INFO - PROCESS | 6438 | 2016-11-05 06:01:36.968 plugin-container[6439:116086] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:01:46 INFO - PROCESS | 6438 | RSS: Main: 239046656 06:01:46 INFO - PROCESS | 6438 | 06:01:49 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57458/tests/kraken/driver.html?testName=audio-beat-detection) 06:01:50 INFO - PROCESS | 6438 | RSS: Main: 251342848 06:01:50 INFO - PROCESS | 6438 | 06:01:53 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57458/tests/kraken/driver.html?testName=audio-dft) 06:01:53 INFO - PROCESS | 6438 | RSS: Main: 258945024 06:01:53 INFO - PROCESS | 6438 | 06:01:57 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57458/tests/kraken/driver.html?testName=audio-fft) 06:01:57 INFO - PROCESS | 6438 | RSS: Main: 261943296 06:01:57 INFO - PROCESS | 6438 | 06:01:59 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57458/tests/kraken/driver.html?testName=audio-oscillator) 06:02:00 INFO - PROCESS | 6438 | RSS: Main: 263979008 06:02:00 INFO - PROCESS | 6438 | 06:02:02 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57458/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:02:03 INFO - PROCESS | 6438 | RSS: Main: 264581120 06:02:03 INFO - PROCESS | 6438 | 06:02:06 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57458/tests/kraken/driver.html?testName=imaging-darkroom) 06:02:06 INFO - PROCESS | 6438 | RSS: Main: 264749056 06:02:06 INFO - PROCESS | 6438 | 06:02:09 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57458/tests/kraken/driver.html?testName=imaging-desaturate) 06:02:10 INFO - PROCESS | 6438 | RSS: Main: 264884224 06:02:10 INFO - PROCESS | 6438 | 06:02:12 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57458/tests/kraken/driver.html?testName=json-parse-financial) 06:02:13 INFO - PROCESS | 6438 | RSS: Main: 265166848 06:02:13 INFO - PROCESS | 6438 | 06:02:15 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57458/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:02:15 INFO - PROCESS | 6438 | RSS: Main: 264974336 06:02:15 INFO - PROCESS | 6438 | 06:02:17 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:02:18 INFO - PROCESS | 6438 | RSS: Main: 265297920 06:02:18 INFO - PROCESS | 6438 | 06:02:20 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:02:21 INFO - PROCESS | 6438 | RSS: Main: 265064448 06:02:21 INFO - PROCESS | 6438 | 06:02:24 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:02:25 INFO - PROCESS | 6438 | RSS: Main: 265228288 06:02:25 INFO - PROCESS | 6438 | 06:02:28 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:02:28 INFO - PROCESS | 6438 | RSS: Main: 265338880 06:02:28 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | Cycle 1(1): loaded http://localhost:57458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57458/tests/kraken/driver.html?testName=ai-astar) 06:02:30 INFO - PROCESS | 6438 | RSS: Main: 267083776 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | __start_tp_report 06:02:30 INFO - PROCESS | 6438 | _x_x_mozilla_page_load 06:02:30 INFO - PROCESS | 6438 | _x_x_mozilla_page_load_details 06:02:30 INFO - PROCESS | 6438 | |i|pagename|runs| 06:02:30 INFO - PROCESS | 6438 | |0;ai-astar;104;118;118;117;117;118;117;119;117;118 06:02:30 INFO - PROCESS | 6438 | |1;audio-beat-detection;149;116;116;151;126;105;143;143;176;119 06:02:30 INFO - PROCESS | 6438 | |2;audio-dft;196;198;253;285;230;201;199;235;193;199 06:02:30 INFO - PROCESS | 6438 | |3;audio-fft;84;60;59;90;78;86;85;57;77;58 06:02:30 INFO - PROCESS | 6438 | |4;audio-oscillator;81;73;119;100;101;104;120;70;74;126 06:02:30 INFO - PROCESS | 6438 | |5;imaging-gaussian-blur;88;86;89;86;90;85;88;87;87;86 06:02:30 INFO - PROCESS | 6438 | |6;imaging-darkroom;95;95;94;95;96;94;95;109;99;95 06:02:30 INFO - PROCESS | 6438 | |7;imaging-desaturate;89;88;90;86;89;96;90;99;88;95 06:02:30 INFO - PROCESS | 6438 | |8;json-parse-financial;56;55;56;57;57;57;57;56;58;57 06:02:30 INFO - PROCESS | 6438 | |9;json-stringify-tinderbox;69;76;76;83;75;70;70;72;73;72 06:02:30 INFO - PROCESS | 6438 | |10;stanford-crypto-aes;111;112;90;90;89;88;91;92;86;86 06:02:30 INFO - PROCESS | 6438 | |11;stanford-crypto-ccm;153;150;151;150;170;148;136;171;225;166 06:02:30 INFO - PROCESS | 6438 | |12;stanford-crypto-pbkdf2;187;210;210;168;240;241;173;174;176;177 06:02:30 INFO - PROCESS | 6438 | |13;stanford-crypto-sha256-iterative;70;73;91;95;79;69;70;81;92;92 06:02:30 INFO - PROCESS | 6438 | __end_tp_report 06:02:30 INFO - PROCESS | 6438 | __start_cc_report 06:02:30 INFO - PROCESS | 6438 | _x_x_mozilla_cycle_collect,946 06:02:30 INFO - PROCESS | 6438 | __end_cc_report 06:02:30 INFO - PROCESS | 6438 | __startTimestamp1478350950903__endTimestamp 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | ------- Summary: start ------- 06:02:30 INFO - PROCESS | 6438 | Number of tests: 14 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#0] ai-astar Cycles:10 Average:116.30 Median:118.00 stddev:4.37 (3.7%) stddev-sans-first:0.71 06:02:30 INFO - PROCESS | 6438 | Values: 104.0 118.0 118.0 117.0 117.0 118.0 117.0 119.0 117.0 118.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#1] audio-beat-detection Cycles:10 Average:134.40 Median:143.00 stddev:21.64 (15.1%) stddev-sans-first:22.30 06:02:30 INFO - PROCESS | 6438 | Values: 149.0 116.0 116.0 151.0 126.0 105.0 143.0 143.0 176.0 119.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#2] audio-dft Cycles:10 Average:218.90 Median:215.50 stddev:31.02 (14.4%) stddev-sans-first:31.77 06:02:30 INFO - PROCESS | 6438 | Values: 196.0 198.0 253.0 285.0 230.0 201.0 199.0 235.0 193.0 199.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#3] audio-fft Cycles:10 Average:73.40 Median:81.00 stddev:13.37 (16.5%) stddev-sans-first:13.62 06:02:30 INFO - PROCESS | 6438 | Values: 84.0 60.0 59.0 90.0 78.0 86.0 85.0 57.0 77.0 58.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:98.00 stddev:21.13 (21.6%) stddev-sans-first:21.62 06:02:30 INFO - PROCESS | 6438 | Values: 81.0 73.0 119.0 100.0 101.0 104.0 120.0 70.0 74.0 126.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:87.50 stddev:1.55 (1.8%) stddev-sans-first:1.62 06:02:30 INFO - PROCESS | 6438 | Values: 88.0 86.0 89.0 86.0 90.0 85.0 88.0 87.0 87.0 86.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:95.00 stddev:4.55 (4.8%) stddev-sans-first:4.78 06:02:30 INFO - PROCESS | 6438 | Values: 95.0 95.0 94.0 95.0 96.0 94.0 95.0 109.0 99.0 95.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#7] imaging-desaturate Cycles:10 Average:91.00 Median:90.00 stddev:4.19 (4.7%) stddev-sans-first:4.38 06:02:30 INFO - PROCESS | 6438 | Values: 89.0 88.0 90.0 86.0 89.0 96.0 90.0 99.0 88.0 95.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:0.84 (1.5%) stddev-sans-first:0.87 06:02:30 INFO - PROCESS | 6438 | Values: 56.0 55.0 56.0 57.0 57.0 57.0 57.0 56.0 58.0 57.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:74.00 stddev:4.14 (5.6%) stddev-sans-first:4.04 06:02:30 INFO - PROCESS | 6438 | Values: 69.0 76.0 76.0 83.0 75.0 70.0 70.0 72.0 73.0 72.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#10] stanford-crypto-aes Cycles:10 Average:93.50 Median:89.50 stddev:9.69 (10.8%) stddev-sans-first:7.94 06:02:30 INFO - PROCESS | 6438 | Values: 111.0 112.0 90.0 90.0 89.0 88.0 91.0 92.0 86.0 86.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#11] stanford-crypto-ccm Cycles:10 Average:162.00 Median:159.50 stddev:24.70 (15.5%) stddev-sans-first:25.99 06:02:30 INFO - PROCESS | 6438 | Values: 153.0 150.0 151.0 150.0 170.0 148.0 136.0 171.0 225.0 166.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.60 Median:198.50 stddev:27.87 (14.0%) stddev-sans-first:29.39 06:02:30 INFO - PROCESS | 6438 | Values: 187.0 210.0 210.0 168.0 240.0 241.0 173.0 174.0 176.0 177.0 06:02:30 INFO - PROCESS | 6438 | 06:02:30 INFO - PROCESS | 6438 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.20 Median:86.00 stddev:10.50 (12.2%) stddev-sans-first:10.32 06:02:30 INFO - PROCESS | 6438 | Values: 70.0 73.0 91.0 95.0 79.0 69.0 70.0 81.0 92.0 92.0 06:02:30 INFO - PROCESS | 6438 | -------- Summary: end -------- 06:02:30 INFO - PROCESS | 6438 | 06:02:31 INFO - TEST-INFO | 6438: exit 0 06:02:31 INFO - TEST-OK | kraken | took 61130ms 06:02:31 INFO - SUITE-END | took 1177s 06:02:32 INFO - Completed test suite (00:19:37) 06:02:32 INFO - javascript benchmark 06:02:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7018.973253911344, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.3128143089953, 3353.2455197580534, 3363.596368435788, 3373.0218236711353, 3395.3718562066006, 4879.975600121995, 4881.487443818392, 4930.802767889285, 5655.802046928358, 5683.2895888014, 4472.7987240574175, 5674.09214525676, 5679.4036626154275, 5705.315362156537, 5727.513161381277, 5153.330067091273, 5209.2707021016895, 5243.449437809032, 5258.368995720519, 5262.868428289305, 4033.939490907624, 5222.373315202181, 5262.158054711247, 5278.445763194867, 5289.233061206142, 5254.973725131369, 5279.762410691509, 5285.154375299952, 5291.100512912795, 5297.523222909937, 5265.842024739245, 5285.814996475144, 5287.867803304929, 5290.735463226862, 5302.867428314285, 4283.828646854122, 5142.794288228466, 5230.660007099565, 5243.0, 5249.343832020997, 5765.423457654243, 5770.913436298459, 5802.390748971339, 5811.622244554091, 5883.79406720767, 6094.055421409688, 6255.437010669016, 6262.906056409158, 6290.874182516324, 6297.0, 6133.457080270857, 6213.56505044647, 6244.687765611701, 6280.560360774748, 6291.874162516724, 5916.083007133901, 5916.556258280646, 5942.168096466498, 5962.34414214436, 5967.582269241155, 6289.7169627366875, 6324.430801227912, 6330.873382532323, 6346.904796428057, 6353.872922541524, 6094.421030002142, 6124.472479235491, 6134.0, 6222.408871157233, 6234.220722409699, 6137.846553836101, 6177.382261773787, 6221.377862213742, 6239.656818874914, 6261.060840873905, 6261.686915654199, 6263.154474146023, 6265.592736472115, 6276.278228003746, 6279.277883043417, 6162.5378096642935, 6165.969170154121, 6225.750969961241, 6256.749730010749, 6311.495080393557, 4955.4796746341835, 4977.751112444364, 4978.751062446863, 4984.077945580079, 4990.3762029746285, 4488.483824360175, 4491.775411229425, 4497.347884556786, 4499.280115181556, 4516.23224051912, 5282.788688452496, 5711.485966263056, 5785.479306862402, 5788.9710551447815, 5790.247267855152, 6244.594101383395, 6261.24865016201, 6278.246610406781, 6278.4035516625845, 6284.685765711696, 5828.475437210672, 5907.764504695301, 5915.467607915309, 5951.434613711691, 5985.22192115031, 5350.705711185922, 5384.219288203189, 5417.3770016447825, 5451.563874889999, 5462.3445186577865, 5487.368952570346, 6103.816885493442, 6268.686565671789, 6281.59169653971, 6315.27374351937, 6242.931238073389, 6280.623162610258, 6297.96851015742, 6303.842403939755, 6320.241571011508, 6187.0, 6201.782937597162, 6228.875422491616, 6242.687865606792, 6259.65571893532, 6202.162708034538, 6215.06773983888, 6271.310155882758, 6290.0879372491645, 6293.653849038347, 6250.781222657185, 6254.562180647493, 6274.937250627618, 6286.779962701284, 6299.874002520016, 4303.612674859278, 4919.508049195052, 4977.726225057657, 5157.200633901752, 5864.003119469615, 5945.970270148621, 6129.222525467564, 6159.23009623797, 6161.0, 6165.290991536031, 5026.4973502649445, 5143.794248230029, 6037.818865434044, 6077.604955677867, 6077.787277445268, 6021.307549631849, 6031.4270144336215, 6156.984097623842, 6165.599236049642, 6166.506679465721, 5904.937111319832, 5977.22296101504, 6019.488343490677, 6037.426444487766, 6100.0849872519475], "value": 5655.887816877581, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.5188396531746, 466.9719816810992, 522.5114517925738, 529.790732660599, 550.8047536845046, 34187.82906085469, 39515.80242098789, 39518.2096358073, 40495.19009619806, 41460.0, 33977.49033764489, 40720.18559628806, 40741.796291018574, 40881.79559102208, 40928.0, 9248.630054797817, 9394.62421503139, 9431.622735090588, 9443.197328227108, 9466.90533094667, 9369.250459963187, 9387.155156035955, 9418.199453046465, 9430.717078487656, 9444.574994125282, 9435.85846212304, 9445.71662850112, 9456.527173641325, 9475.81048379032, 9495.335326527145, 9384.296177786659, 9456.054394560557, 9465.621375145054, 9466.100720431548, 9489.240860731194, 9157.542122893828, 9185.448873067637, 9218.078192180796, 9305.162535371848, 9307.488088155216, 34751.5248475153, 38465.61534384648, 38544.80727596344, 38804.61195388067, 38815.0, 3332.6334103248623, 3438.710483568662, 3822.675072618803, 3939.172773717523, 3950.071733142709, 29505.852470737722, 29795.851020744758, 29867.850660746775, 29996.700032999608, 30734.23164420885, 3222.146131275214, 3251.4960181171955, 3258.218027673341, 3299.2741596848655, 3370.477575975703, 471350.0, 481428.0, 483336.0, 485805.99999999645, 486306.0, 55205.755453231875, 55345.72327138339, 55401.89196216053, 55477.1678424824, 55529.0, 57284.71357643185, 58052.419475805546, 58109.70945145332, 58229.70885145632, 58384.4161558379, 15337.003094798802, 15470.613234669223, 15552.455664051704, 15573.454929077428, 15582.532524024298, 9041.914970203617, 11437.656870293904, 12035.458404371824, 12105.213161144497, 12211.0, 14569.635759106108, 15070.698586028218, 15233.466828660943, 15239.0, 15439.228038598025, 3146.9685303147135, 5146.845594632092, 5867.53311672082, 6088.969555152285, 6115.541334399938, 848.4866655673352, 891.7159290621485, 916.8258030974093, 921.5852866210232, 1120.3725913488472, 24368.87815560911, 28688.569671454945, 29198.124056278346, 29827.85086074556, 30005.099847004625, 993.8310487217046, 1019.4494972714799, 1110.1174566219875, 1128.7178205448638, 1168.9006434452997, 13187.868121318856, 13796.379162937492, 15169.317380717674, 15378.0, 15615.921920390552, 662462.0, 665053.0, 670785.0, 671533.0, 673706.0, 55264.17103743447, 55338.0, 55338.0, 55454.72272638611, 55828.0, 1299.64259828546, 1311.7573248948975, 1347.7439286535528, 1352.1819299323768, 1352.7024054707751, 22852.0, 28843.42313153767, 28860.134195974155, 28943.276418089717, 29490.85254573799, 47827.804304892656, 48036.27945580749, 48065.75967120142, 48090.5190948086, 48109.5189048105, 596346.0182699233, 601249.0, 604675.0, 606370.0, 607084.0, 25522.744772552778, 25870.35324116837, 25886.482270354863, 25916.740832592186, 25951.96192152368, 44044.0, 44487.77756111328, 44687.55312446964, 45819.0, 45885.0, 34196.48705269473, 36227.27545449129, 36693.63306367009, 37396.81301593475, 39688.80155599204, 36688.0, 37018.259634806614, 37383.43924841075, 38412.61587384091, 38759.806200968815, 35915.0, 36489.81755091208, 37343.253134937695, 37593.0, 37741.4338784924, 32042.679573204903, 33574.49638255477, 33688.115223292974, 33827.66172338343, 33896.15259618529, 19758.703619445434, 24657.876710616332, 24815.751842481342, 24823.50352992966, 25394.921015797285], "value": 19122.120022005747, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.93161299353125, 368.90961714379984, 374.3561074951084, 379.5653976197256, 397.0827388732029, 18659.760828700557, 18866.962317072557, 18888.0, 19624.90187549062, 19832.603347933033, 18483.2606695732, 18513.81486185141, 19107.522311942208, 19117.522061948424, 19126.617467650674, 4198.37024446334, 5053.140966035774, 5286.154215325549, 5296.788128474856, 5308.177232528964, 4969.378827646544, 5304.151335786275, 5773.364929857712, 5855.004649209644, 5863.091220860773, 5495.972520137394, 5731.742071606767, 5794.768209271645, 5812.302523697141, 5826.883462330751, 5752.65484070955, 5755.338136114358, 5882.735276912549, 5896.882062358772, 5909.231799866033, 5477.370102438191, 5679.687617181054, 5689.971550142222, 5704.372519022904, 5723.341815691208, 18750.0, 23203.651945220834, 23862.0, 23956.880215598983, 24376.268711938672, 1528.8624023837908, 2232.7320721513524, 2258.9096436142536, 2308.3305841306, 2611.138324352959, 9645.324827262095, 9666.613335466573, 10206.591736330538, 12653.367331633473, 14224.715505689932, 1791.3282519055354, 1847.4088291746646, 1880.172724001435, 1880.8871467711845, 1893.5455490682275, 123530.0, 126197.99999999908, 126938.0, 127912.72087279102, 129255.0, 9592.84885813693, 10895.746989096197, 11174.050205732441, 11267.211295209421, 11509.561304836896, 11637.88362116385, 14132.0, 14259.857401426061, 14262.144271343544, 14285.0, 7947.682092716343, 8205.876911846328, 8227.095019547845, 8306.252437280673, 8368.0, 6984.860302794017, 7105.609191494517, 8004.759857204293, 8057.31512821405, 8149.714759983491, 4388.253996820548, 4415.470143582791, 4485.798139083684, 4488.4165058542185, 4588.839390621312, 1614.612493001671, 1865.8729048884284, 1913.1199648162, 1921.4619906425944, 1924.4419118455492, 827.5117680568374, 846.8475674378672, 852.6205838401852, 872.8952525696957, 933.6312156698066, 15495.380184792708, 19608.411747647595, 19905.90517521521, 19989.500262493555, 20061.896595687376, 760.5056713136505, 888.2228050455851, 897.7351681254046, 966.2077096780713, 976.6337623391229, 7592.0, 7904.0, 8874.757533945252, 10052.095311422006, 10171.220036493583, 486295.0, 520857.0, 559758.0, 568586.0, 595746.0, 111029.88970110357, 118631.40684296524, 122248.38875805565, 132152.0, 132444.3377783105, 903.5346796399859, 967.2310513142069, 1001.3040936549029, 1014.5840205515703, 1016.1058268723476, 16922.15389230509, 18159.909200453912, 18187.636247275244, 18354.90822545879, 19025.52436189051, 41536.79231603823, 43035.78482107569, 50634.98730025305, 55363.72318138384, 56872.715636421555, 72097.6395118021, 73325.26674733184, 74618.62690686512, 77695.0, 77802.61098694697, 7779.611019448892, 7943.086545047394, 7984.640691169027, 8019.1579884111325, 8116.918830811616, 10229.948850255701, 11244.65064192302, 12177.147599668293, 12399.318037508023, 12413.069019823368, 4754.381930349033, 8682.91317086821, 10714.357138571399, 10757.181200673647, 10794.0, 8257.545834979133, 8750.299976002032, 10535.0, 10677.412742299248, 10857.511411986321, 10525.684229473129, 10791.946040269748, 10798.622048228275, 10939.343639381663, 11161.776764464828, 7565.4325925556905, 7780.455368124403, 7843.921560784547, 7848.882266765889, 7926.722564710208, 9749.658761943298, 10436.582536698446, 10670.093042091508, 10677.89322106769, 10744.83882741775], "value": 9090.92582214706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.3906963727047, 1441.2217402602594, 1595.298068849706, 1771.982280177198, 1779.857611391088, 7541.057367829021, 7829.334506566935, 7969.203079692028, 8465.703700370488, 8487.023992240895, 7986.001749781278, 8282.58587070647, 8301.501909885395, 8371.414001019932, 8396.580170991456, 2269.6255117905566, 2303.3205204464725, 2318.3740390094663, 2318.686977258074, 2326.139328448468, 1966.4788830959808, 2311.3874823171873, 2335.5679199348174, 2347.272345572869, 2357.092519380034, 2288.416453804286, 2291.702078729763, 2293.0713061210236, 2317.5017371265176, 2327.9534409311805, 2276.8520046196945, 2281.863088214704, 2299.0688771047753, 2305.4351683837485, 2308.8152947764224, 1927.4603111128906, 2309.6304591265484, 2321.8258630602772, 2324.825638077151, 2338.497223097025, 8330.750077497685, 8350.707725229588, 8394.286485648727, 8405.663773449054, 8410.201030902055, 5046.798128074873, 5174.0, 5217.843464696065, 5231.633785634969, 5247.737613119329, 3025.774561302676, 3265.2000259936467, 3361.344537815127, 3640.3447379471686, 3641.326354624403, 1042.1818872185374, 1057.7461409261798, 1079.6976846482996, 1102.8841971593029, 1102.9889701102966, 10742.0, 10944.835827462595, 10981.011708946233, 10985.725356866062, 11011.669649910515, 4386.473623165177, 4521.321801729767, 4602.171609110393, 4608.654350923694, 4636.79134438951, 9344.579493922789, 9388.483633400228, 9397.436153830791, 9399.71800845962, 9409.858852117224, 4272.829086836554, 4627.676062675648, 4743.952560474351, 5082.567981721595, 5111.853021730882, 3331.666833316649, 3644.726645501598, 4013.337799263147, 4222.19778242133, 4399.14216727735, 3214.3571285742946, 3555.591107022674, 3722.683571896419, 3837.731358804857, 3855.5951625078965, 1264.576366917093, 1267.8732126787247, 1269.2194300505123, 1279.9680007999689, 1282.7498637765732, 752.6725874244721, 803.2088392932957, 814.4705941138308, 883.9348584955113, 892.8749975003504, 5603.3836278009385, 5647.407022262603, 5806.44838740319, 5856.912146317809, 5913.201717768132, 424.21731904635897, 427.5083653798157, 431.0172806002257, 432.95441508756, 435.912817436514, 3769.679577235911, 3800.410936304877, 3865.2849222893856, 3890.105275786585, 3971.324874771296, 8275.379346549033, 10038.548265328078, 10135.341202821794, 10143.0, 10167.23745719074, 9871.0, 9938.552765125587, 10041.297109202433, 10143.391396516232, 10192.439415832052, 7763.9611801941755, 7813.609319534001, 7893.289603935674, 7903.56530390834, 7905.841883162305, 5456.154296084113, 5586.469285418041, 5630.493255607015, 5649.7740090397565, 5676.7445464955, 9387.342885997914, 9403.4828084456, 9436.0, 9436.961934187097, 9449.763755906157, 8935.508547029976, 9302.16280534755, 9303.348765586614, 9484.715458536255, 9485.146336829719, 4885.95114048855, 6759.121314229119, 6898.344657257552, 7066.7173313066905, 7222.458315626244, 7741.380689544711, 7989.0, 7995.240452157036, 8000.719974801087, 8030.95984520097, 7061.0, 7129.001939728546, 7129.964350178216, 7170.605616691132, 7280.089988751406, 6832.316768323128, 6948.409385202112, 6969.765061293705, 7041.436685065285, 7158.355747982707, 6624.337566243337, 6655.0, 6712.563683360566, 6716.093327400738, 6776.525643205126, 4844.975775121102, 4880.219164933594, 4888.608911287018, 4925.285833554186, 4953.603711703127, 4822.68652537584, 4842.95157048425, 4897.975510122426, 4961.255811628284, 4985.825496107619], "value": 4277.226438515151, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [255.95648739714247, 264.6850248204637, 292.3627684964201, 297.12052325117645, 308.4755914944595, 5273.016998936007, 5440.619156659035, 5473.536908391225, 5515.117581187011, 5550.972245138779, 5232.921506177401, 5314.388845282793, 5384.1923711443305, 5388.7305634718305, 5405.972970135143, 3789.2042671038976, 3992.482856514535, 4092.795360231984, 4149.315362965122, 4166.937495937564, 3821.331267028273, 4067.925358067392, 4097.73364731293, 4164.938355514009, 4194.580541945811, 3414.2830005698834, 4064.9390259146135, 4086.8978275543054, 4089.3661482470266, 4090.670532077072, 3233.8383080845865, 3484.0837539353256, 4054.3918412238104, 4056.3307054336146, 4078.9796051019557, 2907.331313797817, 3105.7049580289836, 3539.07983924179, 3540.026492714524, 3560.430331147004, 5029.723365214912, 5250.343707036621, 5274.947250527484, 5309.548688361494, 5411.810586629488, 4494.887402920622, 4648.325992731069, 4659.136345461842, 4662.927526668851, 4692.770933806449, 4602.838900638495, 4825.324454576362, 5371.4897084776885, 5715.999700052515, 5747.367789543188, 3689.022409061574, 3799.618339677689, 3852.5184351956004, 3854.9421758673643, 3888.863889763845, 5481.506664400184, 5817.621854579481, 5961.701914904237, 6007.429294217042, 6008.519318454513, 3395.5246265522846, 3923.6272554107313, 3927.7446965947124, 3943.684505239574, 3951.8221680023894, 3388.745844061705, 3788.659020688096, 3866.845326186921, 3994.2639292085014, 3999.7800120993043, 4613.0, 4622.260438329851, 4628.95371046292, 4636.6058884995155, 4644.117617652635, 5718.771249149988, 5739.253896993364, 5743.052396354638, 5765.434381700187, 5777.740001699934, 1201.024315001817, 1210.6791700199453, 1229.7171650520431, 1255.9575552291576, 1257.5849969510223, 840.9915900841036, 854.9185280620021, 859.7119964811733, 865.9090795466511, 867.6876324523166, 4403.405540252025, 4709.764511774398, 4836.274558816206, 5048.056498248201, 5145.819896303611, 4498.640108791355, 4624.791884365211, 5139.434662187156, 5477.561795056386, 5539.446055394509, 4751.501092385319, 4972.279019542146, 4986.825461108844, 5015.623828212899, 5054.898902021939, 2646.6427032350384, 2848.7008864068976, 2871.9282017949718, 2895.7683385329124, 2934.413117376533, 5075.746212689351, 5133.8716532087, 5711.543076553866, 5718.342390625131, 5718.771249150071, 4659.930101048487, 5453.191606322747, 5658.74535645897, 5724.969505489052, 5752.309722833287, 4752.500987396343, 5725.766950889157, 5734.4838964492565, 5735.36910939788, 5748.160588611604, 5100.817329789537, 5261.736913154404, 5487.945120548743, 5495.230667706604, 5513.807016754555, 5128.3076784633595, 5391.649542779707, 5445.740017892688, 5483.8209784896435, 5505.6696598204235, 5601.619311792553, 5629.240052592848, 5645.40723224072, 5660.943390566042, 5660.943390566042, 3287.375398674244, 3611.5846677631357, 3636.127394391943, 3668.79821609814, 3682.0, 5067.403767813127, 5482.793785367213, 5545.336399080179, 5588.568972496814, 5611.747471363717, 5503.532199763065, 5596.804111856066, 5682.261306030189, 5717.42825717425, 5724.227229323988, 4788.4253889533475, 5557.749901254372, 5649.435056494318, 5659.349174844946, 5712.228849105318, 4075.0219947211863, 4356.542563030963, 5451.046066938191, 5588.580856435865, 5656.943430565641, 5288.0, 5575.080111781511, 5611.410801865909, 5732.337622089714, 5750.7987220447085, 4524.642607217887, 5619.690916999604, 5619.8876022480135, 5623.3814280428305, 5638.689872057076], "value": 4039.765157344758, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.6084536319437, 701.4388489208635, 738.1544147312109, 768.6003278295283, 780.9375249980001, 14081.225532595703, 14769.187694676788, 14816.03695759775, 14838.183899885515, 14883.479078232263, 12970.351482425864, 13313.733725325512, 13410.46358145673, 13480.66298342542, 13512.594622161327, 815.042325267811, 826.8263664630434, 833.8499070167368, 834.7871292820322, 834.7871292820322, 664.1200409457464, 686.5194363945234, 804.6660635836121, 830.2486249943811, 833.0877688930642, 824.1511243419287, 852.1180578101666, 858.047567200407, 858.6222062292602, 858.8539948208819, 853.8847255620474, 857.0187135729586, 858.2790456016913, 859.0636206534875, 860.4579115157474, 637.139861187398, 646.7574659502686, 647.2136354329513, 648.1832890557884, 648.3516483516484, 13408.664783380396, 13717.17696938187, 13773.173609583457, 13849.584512464642, 13864.722705545833, 12708.421392874034, 13128.540501082416, 13136.6715832104, 13154.86845131546, 13363.732725345535, 10919.727006824813, 11122.0, 11196.832047519294, 11314.264572802742, 11507.539698412054, 3587.4080776671817, 3635.218428037958, 3671.504346913186, 3762.473253744478, 3765.4728338032696, 88909.0, 89109.0, 89239.0, 90814.5459272706, 92535.53732231363, 10152.441615711059, 10192.847107293397, 10209.74475638115, 10315.638952636771, 10667.039966403061, 19687.40937771869, 19718.507037323896, 19726.802731972784, 20138.496537586383, 20157.19371225164, 7355.374793142642, 7413.555186688816, 7420.072490938633, 7580.787074068123, 7599.886001709979, 4059.512858457004, 4115.9176816463505, 4130.153318569686, 4203.498119983725, 4283.464566929134, 2959.926001849971, 3318.7179089777637, 3406.880759173417, 3415.487676848493, 3430.708389786896, 638.9680515974183, 643.7038962077497, 647.1974751308438, 647.5078940005629, 654.7904670505393, 566.6033776356567, 609.3668967167944, 634.5875181132209, 672.5729161982078, 677.190757045336, 8411.45325553837, 8780.253678437277, 8905.109489051043, 9040.141186587263, 9151.084891510796, 625.6027422586599, 631.1511017681212, 653.947684185264, 666.0, 671.1945665201778, 3709.5919448860604, 4115.176964607091, 4338.370936214231, 4424.655072579502, 4464.352668863062, 73985.0, 76605.0, 96268.0, 104819.0, 109001.0, 60965.0, 63666.36333636697, 87206.0, 91373.08626913778, 93054.0, 41824.0, 58384.0, 58592.70703646455, 76886.0, 86378.0, 9178.632854685738, 9375.437473751597, 9429.434233945985, 9444.763880903032, 9464.479453630116, 45131.09737805291, 45942.62172134841, 46163.30755038744, 46478.76760616175, 47435.76282118567, 65071.674641628386, 66940.6652966732, 67843.66078169577, 68609.65695171691, 69385.65307173431, 11995.0, 12041.75916481648, 12101.576444824388, 12326.198797078161, 12370.0, 12175.33035683046, 13255.806977372084, 13767.449302027806, 14027.789583156466, 14337.498187563386, 13025.739485210053, 13216.405261763051, 13315.467381305027, 13378.197308161542, 13576.117552359065, 12701.36493175319, 12705.428255728699, 13430.19418834873, 13461.192328460324, 13491.392887320291, 10912.672619821418, 12778.872211277769, 12814.05276786363, 13055.543055992996, 13506.459741610619, 10676.73308167271, 10790.622328218475, 10915.781684366428, 10974.067204287725, 11225.270357427067, 9349.906500935176, 9605.759856003655, 9717.271204659537, 10160.288779785344, 10849.566017359217], "value": 7037.711519374472, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 118.0, 117.0, 117.0, 118.0, 117.0, 119.0, 117.0, 118.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 116.0, 116.0, 151.0, 126.0, 105.0, 143.0, 143.0, 176.0, 119.0], "value": 134.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 253.0, 285.0, 230.0, 201.0, 199.0, 235.0, 193.0, 199.0], "value": 218.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 59.0, 90.0, 78.0, 86.0, 85.0, 57.0, 77.0, 58.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 119.0, 100.0, 101.0, 104.0, 120.0, 70.0, 74.0, 126.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 90.0, 85.0, 88.0, 87.0, 87.0, 86.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 96.0, 94.0, 95.0, 109.0, 99.0, 95.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 86.0, 89.0, 96.0, 90.0, 99.0, 88.0, 95.0], "value": 91.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 57.0, 57.0, 57.0, 57.0, 56.0, 58.0, 57.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 76.0, 83.0, 75.0, 70.0, 70.0, 72.0, 73.0, 72.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 90.0, 90.0, 89.0, 88.0, 91.0, 92.0, 86.0, 86.0], "value": 93.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 150.0, 151.0, 150.0, 170.0, 148.0, 136.0, 171.0, 225.0, 166.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 210.0, 210.0, 168.0, 240.0, 241.0, 173.0, 174.0, 176.0, 177.0], "value": 195.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 91.0, 95.0, 79.0, 69.0, 70.0, 81.0, 92.0, 92.0], "value": 81.2, "unit": "score"}]}]} 06:02:32 INFO - Return code: 0 06:02:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:02:32 ERROR - # TBPL SUCCESS # 06:02:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:02:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:02:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:02:32 INFO - 2016-11-05 06:02:32.206 screenresolution[6443:116465] starting screenresolution argv=screenresolution get 06:02:32 INFO - 2016-11-05 06:02:32.208 screenresolution[6443:116465] Display 0: 1600x1200x32@60 06:02:32 INFO - 2016-11-05 06:02:32.215 screenresolution[6444:116468] starting screenresolution argv=screenresolution list 06:02:32 INFO - Available Modes on Display 0 06:02:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:02:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:02:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:02:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:02:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:02:32 INFO - Intel Iris: 06:02:32 INFO - Chipset Model: Intel Iris 06:02:32 INFO - Type: GPU 06:02:32 INFO - Bus: Built-In 06:02:32 INFO - VRAM (Dynamic, Max): 1536 MB 06:02:32 INFO - Vendor: Intel (0x8086) 06:02:32 INFO - Device ID: 0x0a2e 06:02:32 INFO - Revision ID: 0x0009 06:02:32 INFO - Displays: 06:02:32 INFO - Display: 06:02:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:02:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:02:32 INFO - Display Serial Number: 1600x1200 60 06:02:32 INFO - Main Display: Yes 06:02:32 INFO - Mirror: Off 06:02:32 INFO - Online: Yes 06:02:32 INFO - Rotation: Supported 06:02:32 INFO - Return code: 0 06:02:32 INFO - Running post-action listener: _resource_record_post_action 06:02:32 INFO - [mozharness: 2016-11-05 13:02:32.465039Z] Finished run-tests step (success) 06:02:32 INFO - Running post-run listener: _resource_record_post_run 06:02:32 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 2325504; Write bytes: 706332160; Read time: 21; Write time: 2015 06:02:32 INFO - TinderboxPrint: CPU usage
54.8% 06:02:32 INFO - TinderboxPrint: I/O read bytes / time
2,325,504 / 21 06:02:32 INFO - TinderboxPrint: I/O write bytes / time
706,332,160 / 2,015 06:02:32 INFO - TinderboxPrint: CPU idle
2,193.0 (45.2%) 06:02:32 INFO - TinderboxPrint: CPU system
987.8 (20.4%) 06:02:32 INFO - TinderboxPrint: CPU user
1,669.0 (34.4%) 06:02:32 INFO - TinderboxPrint: Swap in / out
405,135,360 / 0 06:02:32 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395192832; Write bytes: 390572032; Read time: 29858; Write time: 797 06:02:32 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 2325504; Write bytes: 299453952; Read time: 21; Write time: 1169 06:02:32 INFO - Running post-run listener: _upload_blobber_files 06:02:32 INFO - Blob upload gear active. 06:02:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:02:32 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 06:02:32 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'] 06:02:32 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 06:02:33 INFO - (blobuploader) - INFO - Open directory for files ... 06:02:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:02:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:02:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:34 INFO - (blobuploader) - INFO - Done attempting. 06:02:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:02:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:02:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:35 INFO - (blobuploader) - INFO - Done attempting. 06:02:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:02:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:02:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:02:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:02:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:02:37 INFO - (blobuploader) - INFO - Done attempting. 06:02:37 INFO - (blobuploader) - INFO - Iteration through files over. 06:02:37 INFO - Return code: 0 06:02:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:02:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:02:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b94431c6859b9d5f9d800856e1a79a627eb77fce0b5aa40e3c3ca700df94d0ae7a11f74d8176740e7aaf2f541b5de80b3d7e323d84704916c633083d71ede44d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a99dd535d60e1bbc56c3da9f865e44f4096a29225e39f8bcf83c2a6c4898ecc65d6d317379910d7523f2cbfc3a2e4d5b1321c16083450235746ef9f62b607010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b446b5243e86b17ebb3819bbc45f3393f206a845f3e21abc1a6ab8b6183743e723f2cf63ed2da1b6aa580862097e76058884e33a1d6e9ba6593744d89631d"} 06:02:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:02:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:02:37 INFO - Contents: 06:02:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b94431c6859b9d5f9d800856e1a79a627eb77fce0b5aa40e3c3ca700df94d0ae7a11f74d8176740e7aaf2f541b5de80b3d7e323d84704916c633083d71ede44d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a99dd535d60e1bbc56c3da9f865e44f4096a29225e39f8bcf83c2a6c4898ecc65d6d317379910d7523f2cbfc3a2e4d5b1321c16083450235746ef9f62b607010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b446b5243e86b17ebb3819bbc45f3393f206a845f3e21abc1a6ab8b6183743e723f2cf63ed2da1b6aa580862097e76058884e33a1d6e9ba6593744d89631d"} 06:02:37 INFO - Running post-run listener: copy_logs_to_upload_dir 06:02:37 INFO - Copying logs to upload dir... 06:02:37 INFO - mkdir: /builds/slave/test/build/upload/logs 06:02:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.898792 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-05 06:02:37.679468) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 06:02:37.686745) ========= 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.YiETun6bOR/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.wwXwIYbfzC/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b94431c6859b9d5f9d800856e1a79a627eb77fce0b5aa40e3c3ca700df94d0ae7a11f74d8176740e7aaf2f541b5de80b3d7e323d84704916c633083d71ede44d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a99dd535d60e1bbc56c3da9f865e44f4096a29225e39f8bcf83c2a6c4898ecc65d6d317379910d7523f2cbfc3a2e4d5b1321c16083450235746ef9f62b607010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b446b5243e86b17ebb3819bbc45f3393f206a845f3e21abc1a6ab8b6183743e723f2cf63ed2da1b6aa580862097e76058884e33a1d6e9ba6593744d89631d"} build_url:https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010976 build_url: 'https://queue.taskcluster.net/v1/task/SBtEHdD-SE6VY0AS18ESXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b94431c6859b9d5f9d800856e1a79a627eb77fce0b5aa40e3c3ca700df94d0ae7a11f74d8176740e7aaf2f541b5de80b3d7e323d84704916c633083d71ede44d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a99dd535d60e1bbc56c3da9f865e44f4096a29225e39f8bcf83c2a6c4898ecc65d6d317379910d7523f2cbfc3a2e4d5b1321c16083450235746ef9f62b607010", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8e5b446b5243e86b17ebb3819bbc45f3393f206a845f3e21abc1a6ab8b6183743e723f2cf63ed2da1b6aa580862097e76058884e33a1d6e9ba6593744d89631d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 06:02:37.721625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 06:02:37.721921) ========= 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.YiETun6bOR/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.wwXwIYbfzC/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.005464 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 06:02:37.769846) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 06:02:37.770118) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 06:02:37.773402) ========= ========= Total master_lag: 0.28 =========