builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0382 starttime: 1487956147.99 results: success (0) buildid: 20170224073322 builduid: 679e8b7de5644a7cbd1f5e48860b61eb revision: 9bbfa08eb52e869f673a8c2262b1c8082f2a70f4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:07.991535) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:07.991973) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:07.992265) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:08.017742) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:08.018100) ========= 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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-24 09:09:08-- 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% 8.15M=0.001s 2017-02-24 09:09:08 (8.15 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144762 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:08.186528) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:08.186877) ========= 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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.019146 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:08.236795) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-24 09:09:08.237462) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9bbfa08eb52e869f673a8c2262b1c8082f2a70f4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9bbfa08eb52e869f673a8c2262b1c8082f2a70f4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FNeFYdeSGP/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.7hS1CdAz4H/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-24 09:09:08,330 truncating revision to first 12 chars 2017-02-24 09:09:08,331 Setting DEBUG logging. 2017-02-24 09:09:08,331 attempt 1/10 2017-02-24 09:09:08,331 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9bbfa08eb52e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-24 09:09:10,048 unpacking tar archive at: mozilla-beta-9bbfa08eb52e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.967803 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-24 09:09:10.228978) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:10.229300) ========= script_repo_revision: 9bbfa08eb52e869f673a8c2262b1c8082f2a70f4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:10.235216) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:10.235494) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 09:09:10.255444) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-24 09:09:10.255723) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FNeFYdeSGP/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.7hS1CdAz4H/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:09:10 INFO - MultiFileLogger online at 20170224 09:09:10 in /builds/slave/test 09:09:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 09:09:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:09:10 INFO - {'append_to_log': False, 09:09:10 INFO - 'base_work_dir': '/builds/slave/test', 09:09:10 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:09:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:09:10 INFO - 'branch': 'Mozilla-Beta', 09:09:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:09:10 INFO - 'config_files': ('talos/mac_config.py',), 09:09:10 INFO - 'default_actions': ('clobber', 09:09:10 INFO - 'read-buildbot-config', 09:09:10 INFO - 'download-and-extract', 09:09:10 INFO - 'populate-webroot', 09:09:10 INFO - 'create-virtualenv', 09:09:10 INFO - 'install', 09:09:10 INFO - 'run-tests'), 09:09:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:09:10 INFO - 'download_minidump_stackwalk': True, 09:09:10 INFO - 'download_symbols': 'ondemand', 09:09:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:09:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:09:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:09:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:09:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:09:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:09:10 INFO - 'installer_path': 'installer.exe', 09:09:10 INFO - 'log_level': 'info', 09:09:10 INFO - 'log_name': 'talos', 09:09:10 INFO - 'log_to_console': True, 09:09:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:09:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:09:10 INFO - 'opt_config_files': (), 09:09:10 INFO - 'pip_index': False, 09:09:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:10 INFO - 'cmd': ('bash', 09:09:10 INFO - '-c', 09:09:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:10 INFO - 'enabled': True, 09:09:10 INFO - 'halt_on_failure': False, 09:09:10 INFO - 'name': 'check_screen_resolution'},), 09:09:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:09:10 INFO - 'cmd': ('bash', 09:09:10 INFO - '-c', 09:09:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:09:10 INFO - 'enabled': True, 09:09:10 INFO - 'halt_on_failure': False, 09:09:10 INFO - 'name': 'check_screen_resolution'},), 09:09:10 INFO - 'run_cmd_checks_enabled': True, 09:09:10 INFO - 'sps_profile': False, 09:09:10 INFO - 'sps_profile_interval': 0, 09:09:10 INFO - 'suite': 'dromaeojs-e10s', 09:09:10 INFO - 'system_bits': '32', 09:09:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:09:10 INFO - 'title': 't-yosemite-r7-0382', 09:09:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:09:10 INFO - 'use_talos_json': True, 09:09:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:09:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:09:10 INFO - 'work_dir': 'build'} 09:09:10 INFO - [mozharness: 2017-02-24 17:09:10.467640Z] Running clobber step. 09:09:10 INFO - Running pre-action listener: _resource_record_pre_action 09:09:10 INFO - Running main action method: clobber 09:09:10 INFO - rmtree: /builds/slave/test/build 09:09:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:09:11 INFO - Running post-action listener: _resource_record_post_action 09:09:11 INFO - [mozharness: 2017-02-24 17:09:11.973754Z] Finished clobber step (success) 09:09:11 INFO - [mozharness: 2017-02-24 17:09:11.973884Z] Running read-buildbot-config step. 09:09:11 INFO - Running pre-action listener: _resource_record_pre_action 09:09:11 INFO - Running main action method: read_buildbot_config 09:09:11 INFO - Using buildbot properties: 09:09:11 INFO - { 09:09:11 INFO - "project": "", 09:09:11 INFO - "product": "firefox", 09:09:11 INFO - "script_repo_revision": "production", 09:09:11 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:09:11 INFO - "repository": "", 09:09:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:09:11 INFO - "buildid": "20170224073322", 09:09:11 INFO - "pgo_build": "False", 09:09:11 INFO - "basedir": "/builds/slave/test", 09:09:11 INFO - "buildnumber": 12, 09:09:11 INFO - "slavename": "t-yosemite-r7-0382", 09:09:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:09:11 INFO - "platform": "yosemite_r7", 09:09:11 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:09:11 INFO - "branch": "mozilla-beta", 09:09:11 INFO - "repo_path": "releases/mozilla-beta", 09:09:11 INFO - "revision": "9bbfa08eb52e869f673a8c2262b1c8082f2a70f4", 09:09:11 INFO - "stage_platform": "macosx64", 09:09:11 INFO - "builduid": "679e8b7de5644a7cbd1f5e48860b61eb", 09:09:11 INFO - "slavebuilddir": "test" 09:09:11 INFO - } 09:09:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 09:09:11 INFO - Running post-action listener: _resource_record_post_action 09:09:11 INFO - [mozharness: 2017-02-24 17:09:11.977129Z] Finished read-buildbot-config step (success) 09:09:11 INFO - [mozharness: 2017-02-24 17:09:11.977243Z] Running download-and-extract step. 09:09:11 INFO - Running pre-action listener: _resource_record_pre_action 09:09:11 INFO - Running main action method: download_and_extract 09:09:11 INFO - mkdir: /builds/slave/test/build/tests 09:09:11 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')]} 09:09:11 INFO - trying https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 09:09:11 INFO - Downloading https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:09:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 09:09:13 INFO - Downloaded 1165 bytes. 09:09:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 09:09:13 INFO - Using the following test package requirements: 09:09:13 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:09:13 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:09:13 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 09:09:13 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:09:13 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 09:09:13 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:09:13 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:09:13 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 09:09:13 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 09:09:13 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:09:13 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 09:09:13 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:09:13 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 09:09:13 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:09:13 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 09:09:13 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 09:09:13 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 09:09:13 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 09:09:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:09:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:09:13 INFO - Fetch https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:09:14 INFO - Http code: 200 09:09:14 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 09:09:14 INFO - x-amz-cf-id: 4gt2HprwI6YZ1rEocrzmv-wABmjDAfqZB8G3vI63RWiWXAS42KkKpQ== 09:09:14 INFO - x-amz-version-id: EO0dt.SJLgnbVbiY5CjFgWQxcIkToDfl 09:09:14 INFO - Expected file size: 16093357 09:09:14 INFO - Obtained file size: 16093357 09:09:21 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 09:09:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 09:09:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 09:09:21 INFO - Fetch https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 09:09:21 INFO - Http code: 200 09:09:21 INFO - via: 1.1 ae162f6796e551002447afd7c07ec67a.cloudfront.net (CloudFront) 09:09:21 INFO - x-amz-cf-id: S5OmvmikkBKjpW5CxBo6yZlYJSLg3VnGql9qbuJG_fXffaBGuXOWCA== 09:09:21 INFO - x-amz-version-id: EO0dt.SJLgnbVbiY5CjFgWQxcIkToDfl 09:09:21 INFO - Expected file size: 16093357 09:09:21 INFO - Obtained file size: 16093357 09:09:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 09:09:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 09:09:28 INFO - Fetch https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 09:09:29 INFO - Http code: 200 09:09:29 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 09:09:29 INFO - x-amz-cf-id: peMLJNOqHD8fkYLPfIH2Z4T0waMIkW5uh2XoY5IblMFqezipxsExOQ== 09:09:29 INFO - x-amz-version-id: 143h35dJjN63SfCavrz9gt9k2tXpU5k_ 09:09:30 INFO - Expected file size: 14402992 09:09:30 INFO - Obtained file size: 14402992 09:09:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:30 INFO - trying https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:09:30 INFO - Downloading https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 09:09:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 09:09:33 INFO - Downloaded 82111047 bytes. 09:09:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:09:33 INFO - mkdir: /builds/slave/test/properties 09:09:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:09:33 INFO - Writing to file /builds/slave/test/properties/build_url 09:09:33 INFO - Contents: 09:09:33 INFO - build_url:https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 09:09:33 INFO - Running post-action listener: _resource_record_post_action 09:09:33 INFO - Running post-action listener: set_extra_try_arguments 09:09:33 INFO - [mozharness: 2017-02-24 17:09:33.884723Z] Finished download-and-extract step (success) 09:09:33 INFO - [mozharness: 2017-02-24 17:09:33.884847Z] Running populate-webroot step. 09:09:33 INFO - Running pre-action listener: _resource_record_pre_action 09:09:33 INFO - Running main action method: populate_webroot 09:09:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:09:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:09:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:09:33 INFO - '--tppagecycles', 09:09:33 INFO - '7'], 09:09:33 INFO - 'tests': ['tsvgm']}}, 09:09:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:09:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:09:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:09:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:09:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['damp', 'tps']}, 09:09:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'tests': ['damp', 'tps']}, 09:09:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['dromaeo_dom']}, 09:09:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:09:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['basic_compositor_video']}, 09:09:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:09:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['a11yr', 09:09:33 INFO - 'ts_paint', 09:09:33 INFO - 'tpaint', 09:09:33 INFO - 'sessionrestore', 09:09:33 INFO - 'sessionrestore_no_auto_restore', 09:09:33 INFO - 'tabpaint']}, 09:09:33 INFO - 'other-e10s': {'tests': ['a11yr', 09:09:33 INFO - 'ts_paint', 09:09:33 INFO - 'tpaint', 09:09:33 INFO - 'sessionrestore', 09:09:33 INFO - 'sessionrestore_no_auto_restore', 09:09:33 INFO - 'tabpaint']}, 09:09:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:09:33 INFO - 'ts_paint', 09:09:33 INFO - 'tpaint', 09:09:33 INFO - 'sessionrestore', 09:09:33 INFO - 'sessionrestore_no_auto_restore', 09:09:33 INFO - 'tabpaint']}, 09:09:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:09:33 INFO - 'ts_paint', 09:09:33 INFO - 'tpaint', 09:09:33 INFO - 'sessionrestore', 09:09:33 INFO - 'sessionrestore_no_auto_restore', 09:09:33 INFO - 'tabpaint']}, 09:09:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['a11yr', 09:09:33 INFO - 'ts_paint', 09:09:33 INFO - 'tpaint', 09:09:33 INFO - 'sessionrestore', 09:09:33 INFO - 'sessionrestore_no_auto_restore', 09:09:33 INFO - 'tabpaint']}, 09:09:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['a11yr', 09:09:33 INFO - 'ts_paint', 09:09:33 INFO - 'tpaint', 09:09:33 INFO - 'sessionrestore', 09:09:33 INFO - 'sessionrestore_no_auto_restore', 09:09:33 INFO - 'tabpaint']}, 09:09:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['tsvgx', 09:09:33 INFO - 'tsvgr_opacity', 09:09:33 INFO - 'tart', 09:09:33 INFO - 'tscrollx', 09:09:33 INFO - 'cart']}, 09:09:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:09:33 INFO - 'tsvgr_opacity', 09:09:33 INFO - 'tart', 09:09:33 INFO - 'tscrollx', 09:09:33 INFO - 'cart']}, 09:09:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'talos_options': ['--disable-e10s'], 09:09:33 INFO - 'tests': ['tp5o']}, 09:09:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'tests': ['tp5o']}, 09:09:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'talos_options': ['--disable-e10s', 09:09:33 INFO - '--xperf_path', 09:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:33 INFO - 'tests': ['tp5n']}, 09:09:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:09:33 INFO - 'talos_options': ['--xperf_path', 09:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:33 INFO - 'tests': ['tp5n']}}, 09:09:33 INFO - 'talos.zip': {'path': '', 09:09:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:09:33 INFO - Running post-action listener: _resource_record_post_action 09:09:33 INFO - [mozharness: 2017-02-24 17:09:33.904152Z] Finished populate-webroot step (success) 09:09:33 INFO - [mozharness: 2017-02-24 17:09:33.904282Z] Running create-virtualenv step. 09:09:33 INFO - Running pre-action listener: _resource_record_pre_action 09:09:33 INFO - Running main action method: create_virtualenv 09:09:33 INFO - Creating virtualenv /builds/slave/test/build/venv 09:09:33 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:09:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:09:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:09:34 INFO - Using real prefix '/tools/python27' 09:09:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:09:34 INFO - Installing distribute.............................................................................................................................................................................................done. 09:09:37 INFO - Installing pip.................done. 09:09:37 INFO - Return code: 0 09:09:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:09:38 INFO - Reading from file tmpfile_stdout 09:09:38 INFO - Output received: 09:09:38 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) 09:09:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:38 INFO - 'HOME': '/Users/cltbld', 09:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:38 INFO - 'LOGNAME': 'cltbld', 09:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:38 INFO - 'MOZ_NO_REMOTE': '1', 09:09:38 INFO - 'NO_EM_RESTART': '1', 09:09:38 INFO - 'PAGER': '/bin/cat', 09:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:38 INFO - 'PWD': '/builds/slave/test', 09:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:38 INFO - 'SHELL': '/bin/bash', 09:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:09:38 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:38 INFO - 'USER': 'cltbld', 09:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:38 INFO - 'XPC_FLAGS': '0x0', 09:09:38 INFO - 'XPC_SERVICE_NAME': '0', 09:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:09:38 INFO - Cleaning up... 09:09:38 INFO - Return code: 0 09:09:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:38 INFO - 'HOME': '/Users/cltbld', 09:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:38 INFO - 'LOGNAME': 'cltbld', 09:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:38 INFO - 'MOZ_NO_REMOTE': '1', 09:09:38 INFO - 'NO_EM_RESTART': '1', 09:09:38 INFO - 'PAGER': '/bin/cat', 09:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:38 INFO - 'PWD': '/builds/slave/test', 09:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:38 INFO - 'SHELL': '/bin/bash', 09:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:09:38 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:38 INFO - 'USER': 'cltbld', 09:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:38 INFO - 'XPC_FLAGS': '0x0', 09:09:38 INFO - 'XPC_SERVICE_NAME': '0', 09:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:38 INFO - Downloading/unpacking psutil>=3.1.1 09:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:09:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:42 INFO - Installing collected packages: psutil 09:09:42 INFO - Running setup.py install for psutil 09:09:42 INFO - building 'psutil._psutil_osx' extension 09:09:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:09:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:09:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:09:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:09:42 INFO - building 'psutil._psutil_posix' extension 09:09:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:09:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:09:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:09:42 INFO - ^ 09:09:42 INFO - 1 warning generated. 09:09:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:43 INFO - Successfully installed psutil 09:09:43 INFO - Cleaning up... 09:09:43 INFO - Return code: 0 09:09:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:43 INFO - 'HOME': '/Users/cltbld', 09:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:43 INFO - 'LOGNAME': 'cltbld', 09:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:43 INFO - 'MOZ_NO_REMOTE': '1', 09:09:43 INFO - 'NO_EM_RESTART': '1', 09:09:43 INFO - 'PAGER': '/bin/cat', 09:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:43 INFO - 'PWD': '/builds/slave/test', 09:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:43 INFO - 'SHELL': '/bin/bash', 09:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:09:43 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:43 INFO - 'USER': 'cltbld', 09:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:43 INFO - 'XPC_FLAGS': '0x0', 09:09:43 INFO - 'XPC_SERVICE_NAME': '0', 09:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:09:46 INFO - Installing collected packages: mozsystemmonitor 09:09:46 INFO - Running setup.py install for mozsystemmonitor 09:09:46 INFO - Successfully installed mozsystemmonitor 09:09:46 INFO - Cleaning up... 09:09:46 INFO - Return code: 0 09:09:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:09:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:09:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:46 INFO - 'HOME': '/Users/cltbld', 09:09:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:46 INFO - 'LOGNAME': 'cltbld', 09:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:46 INFO - 'MOZ_NO_REMOTE': '1', 09:09:46 INFO - 'NO_EM_RESTART': '1', 09:09:46 INFO - 'PAGER': '/bin/cat', 09:09:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:46 INFO - 'PWD': '/builds/slave/test', 09:09:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:46 INFO - 'SHELL': '/bin/bash', 09:09:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:09:46 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:09:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:46 INFO - 'USER': 'cltbld', 09:09:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:46 INFO - 'XPC_FLAGS': '0x0', 09:09:46 INFO - 'XPC_SERVICE_NAME': '0', 09:09:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:46 INFO - Downloading/unpacking jsonschema==2.5.1 09:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:09:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:49 INFO - Downloading functools32-3.2.3-2.tar.gz 09:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:09:49 INFO - warning: no files found matching '*.txt' 09:09:49 INFO - no previously-included directories found matching 'build' 09:09:49 INFO - no previously-included directories found matching 'dist' 09:09:49 INFO - no previously-included directories found matching '.git*' 09:09:49 INFO - Installing collected packages: jsonschema, functools32 09:09:49 INFO - Running setup.py install for functools32 09:09:49 INFO - warning: no files found matching '*.txt' 09:09:49 INFO - no previously-included directories found matching 'build' 09:09:49 INFO - no previously-included directories found matching 'dist' 09:09:49 INFO - no previously-included directories found matching '.git*' 09:09:49 INFO - Successfully installed jsonschema functools32 09:09:49 INFO - Cleaning up... 09:09:50 INFO - Return code: 0 09:09:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:50 INFO - 'HOME': '/Users/cltbld', 09:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:50 INFO - 'LOGNAME': 'cltbld', 09:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:50 INFO - 'MOZ_NO_REMOTE': '1', 09:09:50 INFO - 'NO_EM_RESTART': '1', 09:09:50 INFO - 'PAGER': '/bin/cat', 09:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:50 INFO - 'PWD': '/builds/slave/test', 09:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:50 INFO - 'SHELL': '/bin/bash', 09:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:09:50 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:50 INFO - 'USER': 'cltbld', 09:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:50 INFO - 'XPC_FLAGS': '0x0', 09:09:50 INFO - 'XPC_SERVICE_NAME': '0', 09:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:09:50 INFO - Cleaning up... 09:09:50 INFO - Return code: 0 09:09:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:50 INFO - 'HOME': '/Users/cltbld', 09:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:50 INFO - 'LOGNAME': 'cltbld', 09:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:50 INFO - 'MOZ_NO_REMOTE': '1', 09:09:50 INFO - 'NO_EM_RESTART': '1', 09:09:50 INFO - 'PAGER': '/bin/cat', 09:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:50 INFO - 'PWD': '/builds/slave/test', 09:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:50 INFO - 'SHELL': '/bin/bash', 09:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:09:50 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:50 INFO - 'USER': 'cltbld', 09:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:50 INFO - 'XPC_FLAGS': '0x0', 09:09:50 INFO - 'XPC_SERVICE_NAME': '0', 09:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:50 INFO - Downloading/unpacking blobuploader==1.2.4 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:53 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:09:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:54 INFO - Downloading docopt-0.6.1.tar.gz 09:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:09:54 INFO - Installing collected packages: blobuploader, requests, docopt 09:09:54 INFO - Running setup.py install for blobuploader 09:09:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:09:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:09:54 INFO - Running setup.py install for requests 09:09:55 INFO - Running setup.py install for docopt 09:09:55 INFO - Successfully installed blobuploader requests docopt 09:09:55 INFO - Cleaning up... 09:09:55 INFO - Return code: 0 09:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09:55 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')]} 09:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:55 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')]} 09:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:55 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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:55 INFO - 'HOME': '/Users/cltbld', 09:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:55 INFO - 'LOGNAME': 'cltbld', 09:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:55 INFO - 'MOZ_NO_REMOTE': '1', 09:09:55 INFO - 'NO_EM_RESTART': '1', 09:09:55 INFO - 'PAGER': '/bin/cat', 09:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:55 INFO - 'PWD': '/builds/slave/test', 09:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:55 INFO - 'SHELL': '/bin/bash', 09:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:09:55 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:55 INFO - 'USER': 'cltbld', 09:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:55 INFO - 'XPC_FLAGS': '0x0', 09:09:55 INFO - 'XPC_SERVICE_NAME': '0', 09:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:55 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-5DRnoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:55 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-lr0ju5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:55 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-tc4LFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4GXUFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-lMEfhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-4tNzVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pOUeXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-rBQi8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-DVZLix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-BwLIpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-dGtRud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-1hj5uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:56 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Vb4Fzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-GWS4Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-oBtwRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-eEvYoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:57 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-kBM7RG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:09:57 INFO - Running setup.py install for manifestparser 09:09:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:09:57 INFO - Running setup.py install for mozcrash 09:09:57 INFO - Running setup.py install for mozdebug 09:09:57 INFO - Running setup.py install for mozdevice 09:09:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Running setup.py install for mozfile 09:09:58 INFO - Running setup.py install for mozhttpd 09:09:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Running setup.py install for mozinfo 09:09:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Running setup.py install for mozInstall 09:09:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Running setup.py install for mozleak 09:09:58 INFO - Running setup.py install for mozlog 09:09:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:09:58 INFO - Running setup.py install for moznetwork 09:09:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Running setup.py install for mozprocess 09:09:59 INFO - Running setup.py install for mozprofile 09:09:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Running setup.py install for mozrunner 09:09:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Running setup.py install for mozscreenshot 09:09:59 INFO - Running setup.py install for moztest 09:09:59 INFO - Running setup.py install for mozversion 09:10:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:00 INFO - Cleaning up... 09:10:00 INFO - Return code: 0 09:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:00 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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:00 INFO - 'HOME': '/Users/cltbld', 09:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:00 INFO - 'LOGNAME': 'cltbld', 09:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:00 INFO - 'MOZ_NO_REMOTE': '1', 09:10:00 INFO - 'NO_EM_RESTART': '1', 09:10:00 INFO - 'PAGER': '/bin/cat', 09:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:00 INFO - 'PWD': '/builds/slave/test', 09:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:00 INFO - 'SHELL': '/bin/bash', 09:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:10:00 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:01 INFO - 'USER': 'cltbld', 09:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:01 INFO - 'XPC_FLAGS': '0x0', 09:10:01 INFO - 'XPC_SERVICE_NAME': '0', 09:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:01 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-wzlpki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:01 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-oXZDqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:01 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-uldHnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:01 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-pK3sSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:01 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-tf0JAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:01 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-npNkcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:01 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-AX7BqR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-6L5tuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-tkoA3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-F1VdZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:02 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)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-czZJqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-cnuQLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-y35Kca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-Gob0TD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-rPSaCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:02 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-8UQRvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:03 INFO - Running setup.py (path:/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/pip-ov_XO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:05 INFO - Downloading blessings-1.6.tar.gz 09:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:10:05 INFO - Installing collected packages: blessings 09:10:05 INFO - Running setup.py install for blessings 09:10:05 INFO - Successfully installed blessings 09:10:05 INFO - Cleaning up... 09:10:06 INFO - Return code: 0 09:10:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:06 INFO - Reading from file tmpfile_stdout 09:10:06 INFO - Current package versions: 09:10:06 INFO - blessings == 1.6 09:10:06 INFO - blobuploader == 1.2.4 09:10:06 INFO - docopt == 0.6.1 09:10:06 INFO - functools32 == 3.2.3-2 09:10:06 INFO - jsonschema == 2.5.1 09:10:06 INFO - manifestparser == 1.1 09:10:06 INFO - mozInstall == 1.12 09:10:06 INFO - mozcrash == 1.0 09:10:06 INFO - mozdebug == 0.1 09:10:06 INFO - mozdevice == 0.48 09:10:06 INFO - mozfile == 1.2 09:10:06 INFO - mozhttpd == 0.7 09:10:06 INFO - mozinfo == 0.9 09:10:06 INFO - mozleak == 0.1 09:10:06 INFO - mozlog == 3.4 09:10:06 INFO - moznetwork == 0.27 09:10:06 INFO - mozprocess == 0.23 09:10:06 INFO - mozprofile == 0.28 09:10:06 INFO - mozrunner == 6.13 09:10:06 INFO - mozscreenshot == 0.1 09:10:06 INFO - mozsystemmonitor == 0.3 09:10:06 INFO - moztest == 0.8 09:10:06 INFO - mozversion == 1.4 09:10:06 INFO - psutil == 3.1.1 09:10:06 INFO - requests == 1.2.3 09:10:06 INFO - wsgiref == 0.1.2 09:10:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:06 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')]} 09:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 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')]} 09:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:06 INFO - 'HOME': '/Users/cltbld', 09:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:06 INFO - 'LOGNAME': 'cltbld', 09:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:06 INFO - 'MOZ_NO_REMOTE': '1', 09:10:06 INFO - 'NO_EM_RESTART': '1', 09:10:06 INFO - 'PAGER': '/bin/cat', 09:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:06 INFO - 'PWD': '/builds/slave/test', 09:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:06 INFO - 'SHELL': '/bin/bash', 09:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:10:06 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:06 INFO - 'USER': 'cltbld', 09:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:06 INFO - 'XPC_FLAGS': '0x0', 09:10:06 INFO - 'XPC_SERVICE_NAME': '0', 09:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:06 INFO - Cleaning up... 09:10:06 INFO - Return code: 0 09:10:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:10:06 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')]} 09:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 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')]} 09:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 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 0x100c81490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100bf7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff3e0e5c500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, '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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:06 INFO - 'HOME': '/Users/cltbld', 09:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:06 INFO - 'LOGNAME': 'cltbld', 09:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:06 INFO - 'MOZ_NO_REMOTE': '1', 09:10:06 INFO - 'NO_EM_RESTART': '1', 09:10:06 INFO - 'PAGER': '/bin/cat', 09:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:06 INFO - 'PWD': '/builds/slave/test', 09:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:06 INFO - 'SHELL': '/bin/bash', 09:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:10:06 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:06 INFO - 'USER': 'cltbld', 09:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:06 INFO - 'XPC_FLAGS': '0x0', 09:10:06 INFO - 'XPC_SERVICE_NAME': '0', 09:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:10:06 INFO - Cleaning up... 09:10:06 INFO - Return code: 0 09:10:06 INFO - Running post-action listener: _resource_record_post_action 09:10:06 INFO - Running post-action listener: _start_resource_monitoring 09:10:06 INFO - Starting resource monitoring. 09:10:06 INFO - [mozharness: 2017-02-24 17:10:06.888154Z] Finished create-virtualenv step (success) 09:10:06 INFO - [mozharness: 2017-02-24 17:10:06.889205Z] Running install step. 09:10:06 INFO - Running pre-action listener: _resource_record_pre_action 09:10:06 INFO - Running main action method: install 09:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:07 INFO - Reading from file tmpfile_stdout 09:10:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:10:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:10:07 INFO - Reading from file tmpfile_stdout 09:10:07 INFO - Output received: 09:10:07 INFO - Usage: mozinstall [options] installer 09:10:07 INFO - Options: 09:10:07 INFO - -h, --help show this help message and exit 09:10:07 INFO - -d DEST, --destination=DEST 09:10:07 INFO - Directory to install application into. [default: 09:10:07 INFO - "/builds/slave/test"] 09:10:07 INFO - --app=APP Application being installed. [default: firefox] 09:10:07 INFO - mkdir: /builds/slave/test/build/application 09:10:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 09:10:28 INFO - Reading from file tmpfile_stdout 09:10:28 INFO - Output received: 09:10:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:10:28 INFO - Running post-action listener: _resource_record_post_action 09:10:28 INFO - [mozharness: 2017-02-24 17:10:28.735609Z] Finished install step (success) 09:10:28 INFO - [mozharness: 2017-02-24 17:10:28.735745Z] Running run-tests step. 09:10:28 INFO - Running pre-action listener: _resource_record_pre_action 09:10:28 INFO - Running main action method: run_tests 09:10:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:10:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:10:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:10:28 INFO - 2017-02-24 09:10:28.749 screenresolution[1913:12941] starting screenresolution argv=screenresolution get 09:10:28 INFO - 2017-02-24 09:10:28.752 screenresolution[1913:12941] Display 0: 1600x1200x32@60 09:10:28 INFO - 2017-02-24 09:10:28.758 screenresolution[1914:12943] starting screenresolution argv=screenresolution list 09:10:28 INFO - Available Modes on Display 0 09:10:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:10:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:10:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:10:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:10:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:10:28 INFO - Intel Iris: 09:10:28 INFO - Chipset Model: Intel Iris 09:10:28 INFO - Type: GPU 09:10:28 INFO - Bus: Built-In 09:10:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:10:28 INFO - Vendor: Intel (0x8086) 09:10:28 INFO - Device ID: 0x0a2e 09:10:28 INFO - Revision ID: 0x0009 09:10:28 INFO - Displays: 09:10:28 INFO - Display: 09:10:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:10:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:10:28 INFO - Display Serial Number: 1600x1200 60 09:10:28 INFO - Main Display: Yes 09:10:28 INFO - Mirror: Off 09:10:28 INFO - Online: Yes 09:10:28 INFO - Rotation: Supported 09:10:28 INFO - Return code: 0 09:10:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:10:28 INFO - Python 2.7.3 09:10:28 INFO - Return code: 0 09:10:28 INFO - grabbing minidump binary from tooltool 09:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:28 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 0x100a95d30>, '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 0x1008fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100a8db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:10:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:10:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:10:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:10:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:10:29 INFO - Return code: 0 09:10:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:10:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:10:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:10:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:10:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:10:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 09:10:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0382 --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 09:10:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FNeFYdeSGP/Render', 09:10:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:29 INFO - 'HOME': '/Users/cltbld', 09:10:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:29 INFO - 'LOGNAME': 'cltbld', 09:10:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:10:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:29 INFO - 'MOZ_NO_REMOTE': '1', 09:10:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:10:29 INFO - 'NO_EM_RESTART': '1', 09:10:29 INFO - 'PAGER': '/bin/cat', 09:10:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:29 INFO - 'PWD': '/builds/slave/test', 09:10:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:10:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:29 INFO - 'SHELL': '/bin/bash', 09:10:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7hS1CdAz4H/Listeners', 09:10:29 INFO - 'TMPDIR': '/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/', 09:10:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:29 INFO - 'USER': 'cltbld', 09:10:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:29 INFO - 'XPC_FLAGS': '0x0', 09:10:29 INFO - 'XPC_SERVICE_NAME': '0', 09:10:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0382', '--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 09:10:29 INFO - mozversion application_buildid: 20170224073322 09:10:29 INFO - mozversion application_changeset: 9bbfa08eb52e869f673a8c2262b1c8082f2a70f4 09:10:29 INFO - mozversion application_display_name: Firefox 09:10:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:10:29 INFO - mozversion application_name: Firefox 09:10:29 INFO - mozversion application_remotingname: firefox 09:10:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:10:29 INFO - mozversion application_vendor: Mozilla 09:10:29 INFO - mozversion application_version: 52.0 09:10:29 INFO - mozversion platform_buildid: 20170224073322 09:10:29 INFO - mozversion platform_changeset: 9bbfa08eb52e869f673a8c2262b1c8082f2a70f4 09:10:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:10:29 INFO - mozversion platform_version: 52.0 09:10:29 INFO - using testdate: 1487956229 09:10:29 INFO - actual date: 1487956229 09:10:29 INFO - starting webserver on 'localhost:51546' 09:10:29 INFO - SUITE-START | Running 2 tests 09:10:29 INFO - TEST-START | dromaeo_css 09:10:29 INFO - operating with platform_type : mac_ 09:10:29 INFO - Initialising browser for dromaeo_css test... 09:10:29 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpSgQ9Pg/profile http://localhost:51546/getInfo.html) 09:10:32 INFO - PROCESS | 1924 | 2017-02-24 09:10:32.272 plugin-container[1927:13112] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 09:10:32 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:32 INFO - PROCESS | 1924 | 2017-02-24 09:10:32.273 plugin-container[1927:13112] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:10:32 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:32 INFO - PROCESS | 1924 | 2017-02-24 09:10:32.274 plugin-container[1927:13112] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:32 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200 09:10:32 INFO - PROCESS | 1924 | colorDepth:24 09:10:32 INFO - PROCESS | 1924 | Browser inner width/height: 1024/689 09:10:32 INFO - PROCESS | 1924 | __metrics 09:10:32 ERROR - PROCESS | 1924 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:10:33 INFO - TEST-INFO | 1924: exit 0 09:10:33 INFO - Browser initialized. 09:10:33 INFO - Running cycle 1/1 for dromaeo_css test... 09:10:33 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpSgQ9Pg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:35 INFO - PROCESS | 1928 | 2017-02-24 09:10:35.541 plugin-container[1929:13248] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 09:10:35 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:35 INFO - PROCESS | 1928 | 2017-02-24 09:10:35.541 plugin-container[1929:13248] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4227, name = 'com.apple.CFPasteboardClient' 09:10:35 INFO - PROCESS | 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:35 INFO - PROCESS | 1928 | 2017-02-24 09:10:35.541 plugin-container[1929:13248] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:45 INFO - PROCESS | 1928 | RSS: Main: 177623040 09:10:45 INFO - PROCESS | 1928 | 09:13:47 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51546/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51546/tests/dromaeo/cssquery-ext.html) 09:13:49 INFO - PROCESS | 1928 | RSS: Main: 183156736 09:13:49 INFO - PROCESS | 1928 | 09:16:51 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51546/tests/dromaeo/cssquery-ext.html (next: http://localhost:51546/tests/dromaeo/cssquery-jquery.html) 09:16:52 INFO - PROCESS | 1928 | RSS: Main: 194920448 09:16:52 INFO - PROCESS | 1928 | 09:19:53 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51546/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51546/tests/dromaeo/cssquery-mootools.html) 09:19:54 INFO - PROCESS | 1928 | RSS: Main: 196575232 09:19:54 INFO - PROCESS | 1928 | 09:22:56 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51546/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51546/tests/dromaeo/cssquery-prototype.html) 09:22:57 INFO - PROCESS | 1928 | RSS: Main: 199757824 09:22:57 INFO - PROCESS | 1928 | 09:25:59 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51546/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51546/tests/dromaeo/cssquery-yui.html) 09:26:00 INFO - PROCESS | 1928 | RSS: Main: 201093120 09:26:00 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:51546/tests/dromaeo/cssquery-yui.html (next: http://localhost:51546/tests/dromaeo/cssquery-dojo.html) 09:29:03 INFO - PROCESS | 1928 | RSS: Main: 201134080 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | __start_tp_report 09:29:03 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 09:29:03 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 09:29:03 INFO - PROCESS | 1928 | |i|pagename|runs| 09:29:03 INFO - PROCESS | 1928 | |0;dojo.html;3534.9293014139716;3546.521219635347;3561.679448849603;3579.9463008054877;3598.4242521196616;6004.669743164124;6033.969830150849;6035.486983606399;6046.969765151173;6066.7573297068175;4996.725180115092;6024.487918526919;6110.327863934975;6112.724927378267;6120;5395.379531353896;5487.807926722566;5494.313210848644;5498.477644623755;5645.717714114298;5558.583106267026;5568.832935011957;5570.192322113311;5580.190872323511;5584.21820945068;5434.7826086956475;5435.592330575203;5493.862653433676;5540.944590554103;5550.056490396642;4389.363542286383;5470.9452905470835;5508.173773933922;5536.750846211889;5540.058190107651;5337.466253374631;5455.672659640394;5465.508104270635;5488.121900495941;5523.171524271352;5851.23933888596;6071.33215346316;6092.51259899207;6127.89697854386;6127.969360153171;6227.750889964442;6229;6238.532110091761;6260.311365749764;6265.7493700251935;6329.6202227866015;6349.36506349366;6371.904421433682;6419.967900160516;6420.165378500812;6109.5723299369065;6125.509959203253;6136.447719705248;6141.293751218667;6144.293406258248;6379.872402551923;6398.9040164397575;6440.227172739301;6471.255805582418;6518.315576864361;6303.621782693053;6314.747410103636;6320.747170113236;6324.747010119636;6331.746730130743;5368.731563421813;6160.568760186834;6356.523260755462;6371.299157092716;6390.201224846895;6216.844578885564;6265.624062556261;6278.748850045947;6278.780242691484;6303.936960630427;6233.657148856856;6307.180066591313;6314.0844577536;6323.620582764956;6329.398707122816;5124.026434977342;5162.896742065138;5163.845084647466;5169.276301317818;5180.300659411007;4548.522405147478;4568.132054909556;4589.931151032737;4621.560951709582;4647.790849411785;5468.507834294933;5965.582409231399;6085.056816193497;6145.078238264206;6161.630302181883;6364.84087897797;6364.936350636527;6378.840528986812;6388.169537960035;6398.1362516059735;6022.608530445507;6097.146399504073;6097.207363042776;6123.2652081750475;6132.93684452123;5089.185730283137;5619.6347237430255;5661.575381846295;5662.462066103713;5700.00249956256;6569.014647802868;6606.966965165239;6628.569143005786;6631.469482441489;6656.7337306509135;6195.287541932736;6253.6560489173535;6290.559660823698;6354;6394.104825324552;6582.210134783665;6604.801855944329;6608.669566521558;6620.602763834186;6622.933770662425;6277.748890044347;6307.274663413767;6308.211473565804;6310.5898116622275;6328.430441260309;6222;6283;6284.685765711604;6348.301686814354;6400.935990640034;5450.482204190595;6229.345918678474;6251.53113516479;6261.279952805304;6272.278687950761;6203.193584833943;6239.188905442264;6246.906296405648;6265.686715664289;6268.153799237227;5214.800595862896;6100.511959043177;6108.0688921796145;6232.25212974446;6256.249250090018;6192.473639740492;6200.224971878515;6212.4408803207925;6289.874202516015;6304.590201636879;6242.469390101892;6256.812295631138;6264.31092579825;6280.120783090462;6280.497560195082 09:29:03 INFO - PROCESS | 1928 | |1;ext.html;490.4727418025623;507.949205079492;515.0805811626246;526.4261954469628;529.700719093712;38010.23979520408;40766.7961660192;40830.18339633209;40892.38661420074;41015.974600634996;42371;42991.78504107483;43002.78498607503;43078.56921430785;43135;9534.475603841785;9697.95151024244;9725.659601913934;9738;9745.171660408874;8522.275606573448;9627.181689556395;9716.222702183844;9720.659776907809;9739.026097390275;9663.75840603987;9680.322377433582;9746.122848943594;9751.561179746928;9761.804763904718;8404.957975210116;9646.565904534313;9732.026797320212;9749.26880483963;9752.658656947044;7736.535807851491;9617.086376794194;9643.08390702882;9699.030096990315;9703.951480242624;39680.801595992125;39773.80678579647;40512.79743601263;40537.78386648405;40613.39079913804;3237.0735978983457;3394.389009978203;3420;3426.8971930842113;4052.1895620875944;29723.959661411962;29762;30295.939642112422;30921.381572368653;32013.039608811774;3188.6970737779875;3332.9333413331597;3336.699697027255;3407.4718418645148;3442.294329662413;416598;496753;503305;504448;505943.4702826499;62305.68847155826;62415.68792156101;62571.374286257465;62609.74780504365;62688.68655656784;65039;65255.67372163109;65672.6716366415;65955.67022164859;65974.34025659777;16585.585360365847;16607.667846643242;16860.494185174466;17077.658446830992;17109.315627375014;12826.67933301675;13138;13156.40796164175;13233.20600763938;13254;16947.491575252763;17237.56906077333;17410.39063632767;17489.86315889463;17516.912415438095;6267.2165979252595;6289.559730818799;6360.1731774869895;6429;6436.098946147543;846.551327796269;942.6041062753523;949.7530642033121;976.3799987008159;976.3799987008301;30116.849415752782;30254;30313.787448502255;30488;30863.228419289248;837.8437755896823;905.7780843693206;1011.5397494140149;1034.4672493665769;1099.0603034405628;13175.736485270241;15467.530584594446;15502.612434689223;15722.921385393;15982.76025859613;632965;634328;643333;644595;646342;58102.41897581055;58583.70708146432;58595.41404585985;58753.4124658748;58984.410155897895;1355.7424089422977;1391.464286249801;1396.694091024895;1403.1721284442228;1406;29340.266493337836;29895.32754915203;29976;30030.24924376908;30382.696173037988;50886.49113508966;51160.976780464924;51183.72040699012;51460.485395147065;51526.22710659418;512744;561397.1930140486;563630;567438;570318;40113.1977360457;41247;41908.58091419169;42168.578314216466;42267.57732422637;45825.77087114543;46826.53173468222;47126;48471.75764121157;48618.75690621524;32048.51927221047;38278;38868;38880.2223955525;40293;37229.6277037237;37879.05249973895;38221;38504.614953851225;38724.80637596794;34575.65424345724;37784.24431511299;39147;39575.81272561828;40241.79274621766;31634.525482118246;32958;33782.83108584442;33947.32105357928;34140.29298535113;21617.958492164587;25697.614535781606;25810;26063.478730424908;27332.726672733017 09:29:03 INFO - PROCESS | 1928 | |2;jquery.html;382.98468061277566;384.7460675953871;387.5620548779878;394.14077311461006;416.41493701349617;19120.808791912077;20452.795472045258;20513.38459846203;20552.38342849717;21020.36938891832;19992.901455201685;20045.298414555495;20550.69173962388;20819.791802081974;20851.791482085173;5653.378128405882;6100;6116.602420842653;6157.014877619582;6173.845653858667;5753.942460575383;5894.9978503654265;5970.522358211354;6062.4543791058795;6074.331823499411;5918.348981612019;5993.88012239755;5996.700164991733;6000.969995150018;6013.729382177812;5871.70641467927;5966.40335966402;5989.64062156272;6232.470240029603;6599.274079851212;5846.532277417797;5891.05743081105;5969.611975221596;5975.163477113208;5988.071848863433;23904.402389940216;24304.39239019021;24698.382540436454;25032.624510632355;25205;2148.6347320955474;2180.2805074325593;2300.068472268717;2327.8254130940077;2559.462512872299;10035.193665140272;10304.587816487407;10336.224783141295;10358.741031474274;13255.740704633045;1843.2813191292591;1865.412395095554;1872.7565416495768;1889.527618095476;1925.1817977359706;86169;112968.87031129666;114535.42732286286;114633;114665.85334146635;9460.668876589423;10358.378497290187;10362.689119326433;10897.346159230472;10954.39750813697;12939.417726202344;13052.477900884049;13064.150830196008;13067;13072.869271307165;8178.182181781894;8326.084130745614;8346.207110324509;8442.915570844212;8482.533460659723;8531.976162860496;8555.315574754004;8577.228049475578;8719.694810681596;8770.736877893672;3514.8594056237466;4095.119549296856;4140;4175.827433387847;4213.346931225665;1920.7887132415424;1967.537628657293;1971.960560788805;1972.7928567500205;1973.6348775476583;824.5135370131651;832.8750687396939;896.3098414221014;954.7279025477706;991.3407583956588;20525.38423847287;20970.895145524173;21116.78883211148;21337.573248534944;21395.46511337229;774.9612519374009;919.9448033118034;936.8547875079373;939.7979434421633;947.4362754161263;9192.862107068398;10634.255602107933;11105.444727763579;11132.443377831076;11196.664100076849;534243;580899;583163;592225;624692;85807;126593;126953;133495;137304;846.6824940647257;935.6444551070549;991.1723710701501;993.8260804359209;994.2493417469764;17971;18256.90871545687;18604.44186674402;18681.252749890395;18833.90583047076;53251;53369.733151333996;54585.454145459626;55811;55945;53920;72118.27881721116;73415;73869.6306518464;75808;4907.067657145131;5051.772670229922;7905.656038473358;7916.327112195528;8142.83714325722;10372.636957706443;12152.331621760888;12499.312537810507;12553.184043037174;12765.361731913554;10683.57491543401;11197.160212984221;11363.48864301125;11408.258463199865;11524.250923689933;9364.531773411167;10851.728706782078;11040.392778397265;11107.000369966741;11141.777164456504;11059.115270778479;11195.160362972973;11238.719032024264;11430.25703329281;11593.536258549564;8143.714969976022;8188.713395031145;8195.303399211134;8229.423940324119;8409.789755256168;7924.841503169789;10758.193135514712;11220.1023918088;11326.943365283121;11341.489632966372 09:29:03 INFO - PROCESS | 1928 | |3;mootools.html;1402.6773842016335;1471.3673120558162;1656.4450908945503;1665.8084320303162;1801.6576850398415;7493.9250607493905;7700.383969282458;8020.518768873864;8468.19552142547;8478.830423391537;7939.245771651684;8215.548144852031;8234.21774931382;8312.168783121684;8355.164483551642;2343.238447504559;2353.105819788478;2364.9645255321184;2369.1944738788807;2373.7982271506858;2289.3246492284734;2341.84777989431;2383.2731017039796;2402.8678422686744;2404.699412573428;1953.5213872601232;2154.278316763886;2372.181597348912;2378.3340664613847;2455.8035357171384;2327.2203811723093;2328.1967721136266;2355.3287313115766;2355.729091154505;2356.587597170497;2354.0466111224982;2375.2874137758777;2387.5224955009066;2389.569877422063;2397.44858682504;8329.791755206108;8330;8408.159184081604;8435.156484351577;8483.321334293241;5055.368078990126;5109.770060347293;5235.581153507748;5291.126964050927;5294.947050529484;3399.8130102844334;3491.4064609016527;3548.183917698944;3629.872954446582;3636.799976001319;1050.3435352904435;1071.9571217151304;1115.3530952047793;1121.5121422181378;1126.808442564766;10817.242793004492;10868.347899126078;10882.347059176474;10917.290376125606;10919.563217471292;4478.261086920622;4678.508756580578;4772.093302272557;4782.187028205144;4783.521647835259;9198.632054717737;9372.765680857896;9377.1091746284;9379.812403751886;9423.38747981379;4583.702059366197;4985.626078044161;5228.816991405359;5231.581473482189;5246.947530524722;4322.762248076386;4398.846040388635;4451.488078870972;4649.651276154234;4748.430188377417;3310.2220978070136;3844.3464611016193;3922.6466868930174;3949.9012524686536;4012.658923991435;1134.387430787382;1190.7972947323092;1250.9812352814715;1295.9287239201935;1307.5096838685492;759.5632511306021;863.6027427383474;913.2055112052424;915.1260546178383;922.9123233292827;4426.712263702849;5621.522170615462;5669.40471250521;5694.4590263924865;5733.3406658234835;907.577976241051;938.2212763406357;954.8376775948105;966.6954909203579;976.4239098931522;3924.548676902136;3955.773710149805;4075.673946084306;4106.055607210299;4163.167366526707;8865.379423440427;9819.216571980134;9951.900480995097;10139.188864890792;10140.340877842917;9878;9948.403095814274;10111;10134.797304053878;10151.086402223835;7756.612169391507;7807.921920780833;7838.960805196051;7847.921520784833;7925.76222713308;5779;5881.852953676193;5917.053271476543;5993.460588547052;6026.6082704625815;9198.264138869008;9201.217896478875;9335.953320233355;9356.812863742824;9390.295727820305;8948.373613847229;9309.581068851783;9321.86017209756;9416.10546998034;9418.858717119112;6602.504812139013;6775.762848300285;6911.89632155508;7082.787516374517;7234.63826808668;7712.460127791001;7976.962994810638;7978;7983.401244906771;8013;6319.3364696706185;7007.159140903124;7088.503804733619;7105.786826395217;7112.431005519443;6185.443310102112;6857.965710171417;7023.789286321418;7042.507024508235;7101;6599.109120268702;6747.291534388818;6760.594364338155;6761.797146085625;6789.966050169717;4602.815887364468;4837.95162048375;4909.631777616622;4920.8769780755765;4922.655414120977;4839.516048395132;4855.635827312895;4959.652824302264;4967.4287456941465;4994.80020799164 09:29:03 INFO - PROCESS | 1928 | |4;prototype.html;311.8347275943749;314.81897908702496;324.54077480365277;325.0978534566579;328.2663247641521;4379.934300985484;5233.476652334764;5376.258108364743;5493.807716729915;5515.8692468044055;5104.6937183769;5172.896542069157;5243.86890327742;5334.973325133369;5347.866303342418;3456.308738252347;4107.527634322047;4145.502539695241;4152.854650087247;4163.83344666213;3185.012646079723;3567.9108022299392;4018.0557568971267;4133.91732165358;4179;4092.3043082676018;4094.7133700640966;4096.947773908442;4098.8192044114985;4129.9174016519655;3567.8751243706474;4089.5910408959016;4110.23960567296;4111.773852438115;4131.235721391552;3614.9332001498315;3628.092976755811;3633.3096711624707;3641.8543258269638;3652.5799533053773;4413.823447062114;4694.201985662446;4941.579965702919;5119.462456442057;5259.8434641796675;4748.145333839908;4755.262934245197;4778.4265888093305;4781.569658730731;4783.976080119612;4768;5583.687833359158;5670.716464176775;5686.289213848269;5746.798862039808;3227.8708851645906;3641.890743277706;3648.8175591220597;3858.3247931611854;3993.7204395692315;5728.169415434781;5995.52035837132;6004.819855404345;6018.127371531147;6067.787627433063;3398.2329188821673;3548;3935.897948574404;3989.4614227079555;4016.6987475939422;3502.614712381636;3663;3780.164876342743;3923.4114882767813;4065.2682517147196;3950.7036972226624;4173.436586060843;4655.627549796008;4659.184642687516;4688.578027977498;4822.71063736177;5429.56563474921;5780.092569452071;5824.94175058244;5849.61977471463;1257.1953949472345;1267.9032636769314;1281.8525869524938;1287.7102651903247;1307.6338625184962;775.87110753853;870.4636316900676;892.9866052009226;893.2942975049638;895;4516.706414083074;5127.102757017507;5139.383274007143;5141.742912854343;5184.7926082956255;5220.686758794484;5616.663000219918;5618.887622247532;5636.689982050944;5670.639046628779;4636.605888499448;4692.897169165243;4861.51384861511;5130.4356520782685;5139.177731562932;2711.701712811589;2751.4179346875626;2857.614057182265;2902.4050069735654;2951.881924722987;4974.751262436864;5042.613281347585;5779.797707080316;5789.205346342647;5804.543728894825;5669.971650141723;6034.396560344018;6097.634141951497;6116.418940200674;6118.43025929102;5485.5189098943065;5923.4076592340425;6260.499160067184;6302.968485157545;6311.810645680637;5930.792422265373;6025.156478092983;6033.276006879027;6046;6049.546284028627;4732.739699316433;5736.483716465538;5966.582339236212;6016.465801220661;6027.879442411215;4880.633952453509;5608.6073974821375;5917.408259174221;5974.312954010345;6088.908666370096;3984.5019372578427;3997.760134391946;4011.298022845932;4027.8791636250958;4037.9596204037584;5866.853328666817;6033.095035744674;6077.392260773887;6087.147799308189;6092.390760923872;5696.177223288673;5701.092353525322;5705.572082093942;5765.4811067004175;5865.3254875689845;4860.708357498562;5089.567386772166;5412.83761487156;5854.765809367577;5894.823155305347;4670.859874203779;5265.578753699619;5447.6769170806565;5571.470710282517;5659.9151012735665;5827.94172058274;5849.303701926394;5878.970605146947;5884.382139875423;5912.201852749824;4836.540528649773;5303.840884773463;5322.281491998686;5525.320219693365;5654.943450565606 09:29:03 INFO - PROCESS | 1928 | |5;yui.html;671.1744554198333;698.6890833579057;703.9155301363837;706.1808302369253;712.2805965974366;15714.607134821636;16576.33694652214;16589.419370322026;16717.582060448494;16931;15033.323500442479;15342.769858452131;15484.070955742664;15609.98535095218;15960;854.7393045121252;884.2748945864394;889.3241136736095;890.1143362354467;890.8262888736699;696.9930300696979;722.2452537098744;855.3841234311317;873.4322690251356;874.0210963720642;923.1968187676697;924.0066928052337;924.1313165624325;926.4996901673122;927.3184209605935;908.7955210077746;916.573793186165;916.5875356089787;917.1104029091804;917.3899356927635;676.9018492318636;680.1905732178741;684.3498676257525;684.832216107057;686.584616307132;13751.862481375158;13870.237136957463;13904.721905561832;13993.790093148611;14411.78382324266;11699.824502632468;13458.932705336509;13623.931880340633;13675.042747007714;13788.93105534476;11118.833217501744;11285.266457680225;11590.014848737868;11631.53473861053;11759.47082381295;3669.669729724311;3701.3152566774966;3738.738288319819;3882.3400021996326;3923.6861051115843;92668;93543;93728.53135734345;95759.52120239355;96307;10302.330348527472;10460.790784184426;10504.947475262576;10526.157907367393;10651.254412191227;20370.083346249456;20449.795502044788;20567.971601419868;20721.481962950744;20803.79196208049;7981.800454988672;7986.720464783704;8048.798780030429;8138.3896207784655;8198.262156405834;4198.895027624273;4378.605925466723;4475.977620111944;4477.3731677565165;4586.518415566317;3368.2758206985613;3446.138465383654;3465.965340346564;3485.390056740111;3490.0402389342626;640.3724350136891;644.452215616722;648.007377314759;656.7340227207989;659.3241927024742;599.5323647554915;660.4914216053612;661.3221448015822;671.717878491035;683.5249501596442;7134.643267836587;8346.956630421197;8481.491110533389;9207.447553146832;9523.380980236263;527.9498447647468;568.3095039526994;640.3340525853154;648.4455790299227;650.54786923088;4396.208682437128;4405.933910991338;4589.724616523019;4647.62818974488;4715.104130215248;80156;90862.54568727246;112701;115307;117294.41352793352;82734.58632706918;97887.53168702475;99387.01838944844;102141;103092;88315;90044.5497772507;91714.54142729244;92454.53772731093;93895;8894.377393582387;8922.464652120894;8934.687285944961;8941.37410381267;8968.910310896808;43737.56262437335;55318;57466;58145.41854581569;59758.75439718497;77582.2241777575;81526;81712.59143704243;83245;85014.14985850062;11388.601398950997;11392.94303528477;11578;11583.536658533565;11763.294202347886;12112.636620901387;12409.866983822274;12564.37178141071;12654.430550625428;12696.74606507883;11551.364674942623;12297.569585064837;12377.195482293622;12389.62831115068;12400.945919596574;11271.887281127412;11757.706057348634;11764.470598823245;11808.232464889754;11988.280703157838;10347.223958203014;10977;11081.058110060736;11083.390413527333;11414.258073225546;9459.479728614991;9489.1934185595;9490.525473726424;9615.51922403863;10072.798544029225;7241.565506069653;9174.495402752913;9206.309526785384;10133.08802207805;10150.593976240867 09:29:03 INFO - PROCESS | 1928 | __end_tp_report 09:29:03 INFO - PROCESS | 1928 | __start_cc_report 09:29:03 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,372 09:29:03 INFO - PROCESS | 1928 | __end_cc_report 09:29:03 INFO - PROCESS | 1928 | __startTimestamp1487957343529__endTimestamp 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | ------- Summary: start ------- 09:29:03 INFO - PROCESS | 1928 | Number of tests: 6 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:5932.06 Median:6195.29 stddev:634.91 (10.2%) stddev-sans-first:608.37 09:29:03 INFO - PROCESS | 1928 | Values: 3534.9 3546.5 3561.7 3579.9 3598.4 6004.7 6034.0 6035.5 6047.0 6066.8 4996.7 6024.5 6110.3 6112.7 6120.0 5395.4 5487.8 5494.3 5498.5 5645.7 5558.6 5568.8 5570.2 5580.2 5584.2 5434.8 5435.6 5493.9 5540.9 5550.1 4389.4 5470.9 5508.2 5536.8 5540.1 5337.5 5455.7 5465.5 5488.1 5523.2 5851.2 6071.3 6092.5 6127.9 6128.0 6227.8 6229.0 6238.5 6260.3 6265.7 6329.6 6349.4 6371.9 6420.0 6420.2 6109.6 6125.5 6136.4 6141.3 6144.3 6379.9 6398.9 6440.2 6471.3 6518.3 6303.6 6314.7 6320.7 6324.7 6331.7 5368.7 6160.6 6356.5 6371.3 6390.2 6216.8 6265.6 6278.7 6278.8 6303.9 6233.7 6307.2 6314.1 6323.6 6329.4 5124.0 5162.9 5163.8 5169.3 5180.3 4548.5 4568.1 4589.9 4621.6 4647.8 5468.5 5965.6 6085.1 6145.1 6161.6 6364.8 6364.9 6378.8 6388.2 6398.1 6022.6 6097.1 6097.2 6123.3 6132.9 5089.2 5619.6 5661.6 5662.5 5700.0 6569.0 6607.0 6628.6 6631.5 6656.7 6195.3 6253.7 6290.6 6354.0 6394.1 6582.2 6604.8 6608.7 6620.6 6622.9 6277.7 6307.3 6308.2 6310.6 6328.4 6222.0 6283.0 6284.7 6348.3 6400.9 5450.5 6229.3 6251.5 6261.3 6272.3 6203.2 6239.2 6246.9 6265.7 6268.2 5214.8 6100.5 6108.1 6232.3 6256.2 6192.5 6200.2 6212.4 6289.9 6304.6 6242.5 6256.8 6264.3 6280.1 6280.5 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:70048.58 Median:229253.29 stddev:150649.89 (65.7%) stddev-sans-first:150981.98 09:29:03 INFO - PROCESS | 1928 | Values: 490.5 507.9 515.1 526.4 529.7 38010.2 40766.8 40830.2 40892.4 41016.0 42371.0 42991.8 43002.8 43078.6 43135.0 9534.5 9698.0 9725.7 9738.0 9745.2 8522.3 9627.2 9716.2 9720.7 9739.0 9663.8 9680.3 9746.1 9751.6 9761.8 8405.0 9646.6 9732.0 9749.3 9752.7 7736.5 9617.1 9643.1 9699.0 9704.0 39680.8 39773.8 40512.8 40537.8 40613.4 3237.1 3394.4 3420.0 3426.9 4052.2 29724.0 29762.0 30295.9 30921.4 32013.0 3188.7 3332.9 3336.7 3407.5 3442.3 416598.0 496753.0 503305.0 504448.0 505943.5 62305.7 62415.7 62571.4 62609.7 62688.7 65039.0 65255.7 65672.7 65955.7 65974.3 16585.6 16607.7 16860.5 17077.7 17109.3 12826.7 13138.0 13156.4 13233.2 13254.0 16947.5 17237.6 17410.4 17489.9 17516.9 6267.2 6289.6 6360.2 6429.0 6436.1 846.6 942.6 949.8 976.4 976.4 30116.8 30254.0 30313.8 30488.0 30863.2 837.8 905.8 1011.5 1034.5 1099.1 13175.7 15467.5 15502.6 15722.9 15982.8 632965.0 634328.0 643333.0 644595.0 646342.0 58102.4 58583.7 58595.4 58753.4 58984.4 1355.7 1391.5 1396.7 1403.2 1406.0 29340.3 29895.3 29976.0 30030.2 30382.7 50886.5 51161.0 51183.7 51460.5 51526.2 512744.0 561397.2 563630.0 567438.0 570318.0 40113.2 41247.0 41908.6 42168.6 42267.6 45825.8 46826.5 47126.0 48471.8 48618.8 32048.5 38278.0 38868.0 38880.2 40293.0 37229.6 37879.1 38221.0 38504.6 38724.8 34575.7 37784.2 39147.0 39575.8 40241.8 31634.5 32958.0 33782.8 33947.3 34140.3 21618.0 25697.6 25810.0 26063.5 27332.7 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:33866.27 Median:383.87 stddev:97224.89 (25327.9%) stddev-sans-first:97465.12 09:29:03 INFO - PROCESS | 1928 | Values: 383.0 384.7 387.6 394.1 416.4 19120.8 20452.8 20513.4 20552.4 21020.4 19992.9 20045.3 20550.7 20819.8 20851.8 5653.4 6100.0 6116.6 6157.0 6173.8 5753.9 5895.0 5970.5 6062.5 6074.3 5918.3 5993.9 5996.7 6001.0 6013.7 5871.7 5966.4 5989.6 6232.5 6599.3 5846.5 5891.1 5969.6 5975.2 5988.1 23904.4 24304.4 24698.4 25032.6 25205.0 2148.6 2180.3 2300.1 2327.8 2559.5 10035.2 10304.6 10336.2 10358.7 13255.7 1843.3 1865.4 1872.8 1889.5 1925.2 86169.0 112968.9 114535.4 114633.0 114665.9 9460.7 10358.4 10362.7 10897.3 10954.4 12939.4 13052.5 13064.2 13067.0 13072.9 8178.2 8326.1 8346.2 8442.9 8482.5 8532.0 8555.3 8577.2 8719.7 8770.7 3514.9 4095.1 4140.0 4175.8 4213.3 1920.8 1967.5 1972.0 1972.8 1973.6 824.5 832.9 896.3 954.7 991.3 20525.4 20970.9 21116.8 21337.6 21395.5 775.0 919.9 936.9 939.8 947.4 9192.9 10634.3 11105.4 11132.4 11196.7 534243.0 580899.0 583163.0 592225.0 624692.0 85807.0 126593.0 126953.0 133495.0 137304.0 846.7 935.6 991.2 993.8 994.2 17971.0 18256.9 18604.4 18681.3 18833.9 53251.0 53369.7 54585.5 55811.0 55945.0 53920.0 72118.3 73415.0 73869.6 75808.0 4907.1 5051.8 7905.7 7916.3 8142.8 10372.6 12152.3 12499.3 12553.2 12765.4 10683.6 11197.2 11363.5 11408.3 11524.3 9364.5 10851.7 11040.4 11107.0 11141.8 11059.1 11195.2 11238.7 11430.3 11593.5 8143.7 8188.7 8195.3 8229.4 8409.8 7924.8 10758.2 11220.1 11326.9 11341.5 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5441.96 Median:5230.20 stddev:2942.55 (56.3%) stddev-sans-first:2935.14 09:29:03 INFO - PROCESS | 1928 | Values: 1402.7 1471.4 1656.4 1665.8 1801.7 7493.9 7700.4 8020.5 8468.2 8478.8 7939.2 8215.5 8234.2 8312.2 8355.2 2343.2 2353.1 2365.0 2369.2 2373.8 2289.3 2341.8 2383.3 2402.9 2404.7 1953.5 2154.3 2372.2 2378.3 2455.8 2327.2 2328.2 2355.3 2355.7 2356.6 2354.0 2375.3 2387.5 2389.6 2397.4 8329.8 8330.0 8408.2 8435.2 8483.3 5055.4 5109.8 5235.6 5291.1 5294.9 3399.8 3491.4 3548.2 3629.9 3636.8 1050.3 1072.0 1115.4 1121.5 1126.8 10817.2 10868.3 10882.3 10917.3 10919.6 4478.3 4678.5 4772.1 4782.2 4783.5 9198.6 9372.8 9377.1 9379.8 9423.4 4583.7 4985.6 5228.8 5231.6 5246.9 4322.8 4398.8 4451.5 4649.7 4748.4 3310.2 3844.3 3922.6 3949.9 4012.7 1134.4 1190.8 1251.0 1295.9 1307.5 759.6 863.6 913.2 915.1 922.9 4426.7 5621.5 5669.4 5694.5 5733.3 907.6 938.2 954.8 966.7 976.4 3924.5 3955.8 4075.7 4106.1 4163.2 8865.4 9819.2 9951.9 10139.2 10140.3 9878.0 9948.4 10111.0 10134.8 10151.1 7756.6 7807.9 7839.0 7847.9 7925.8 5779.0 5881.9 5917.1 5993.5 6026.6 9198.3 9201.2 9336.0 9356.8 9390.3 8948.4 9309.6 9321.9 9416.1 9418.9 6602.5 6775.8 6911.9 7082.8 7234.6 7712.5 7977.0 7978.0 7983.4 8013.0 6319.3 7007.2 7088.5 7105.8 7112.4 6185.4 6858.0 7023.8 7042.5 7101.0 6599.1 6747.3 6760.6 6761.8 6790.0 4602.8 4838.0 4909.6 4920.9 4922.7 4839.5 4855.6 4959.7 4967.4 4994.8 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4604.70 Median:5128.77 stddev:1451.27 (28.3%) stddev-sans-first:1419.12 09:29:03 INFO - PROCESS | 1928 | Values: 311.8 314.8 324.5 325.1 328.3 4379.9 5233.5 5376.3 5493.8 5515.9 5104.7 5172.9 5243.9 5335.0 5347.9 3456.3 4107.5 4145.5 4152.9 4163.8 3185.0 3567.9 4018.1 4133.9 4179.0 4092.3 4094.7 4096.9 4098.8 4129.9 3567.9 4089.6 4110.2 4111.8 4131.2 3614.9 3628.1 3633.3 3641.9 3652.6 4413.8 4694.2 4941.6 5119.5 5259.8 4748.1 4755.3 4778.4 4781.6 4784.0 4768.0 5583.7 5670.7 5686.3 5746.8 3227.9 3641.9 3648.8 3858.3 3993.7 5728.2 5995.5 6004.8 6018.1 6067.8 3398.2 3548.0 3935.9 3989.5 4016.7 3502.6 3663.0 3780.2 3923.4 4065.3 3950.7 4173.4 4655.6 4659.2 4688.6 4822.7 5429.6 5780.1 5824.9 5849.6 1257.2 1267.9 1281.9 1287.7 1307.6 775.9 870.5 893.0 893.3 895.0 4516.7 5127.1 5139.4 5141.7 5184.8 5220.7 5616.7 5618.9 5636.7 5670.6 4636.6 4692.9 4861.5 5130.4 5139.2 2711.7 2751.4 2857.6 2902.4 2951.9 4974.8 5042.6 5779.8 5789.2 5804.5 5670.0 6034.4 6097.6 6116.4 6118.4 5485.5 5923.4 6260.5 6303.0 6311.8 5930.8 6025.2 6033.3 6046.0 6049.5 4732.7 5736.5 5966.6 6016.5 6027.9 4880.6 5608.6 5917.4 5974.3 6088.9 3984.5 3997.8 4011.3 4027.9 4038.0 5866.9 6033.1 6077.4 6087.1 6092.4 5696.2 5701.1 5705.6 5765.5 5865.3 4860.7 5089.6 5412.8 5854.8 5894.8 4670.9 5265.6 5447.7 5571.5 5659.9 5827.9 5849.3 5879.0 5884.4 5912.2 4836.5 5303.8 5322.3 5525.3 5654.9 09:29:03 INFO - PROCESS | 1928 | 09:29:03 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:20855.12 Median:27943.15 stddev:31004.07 (111.0%) stddev-sans-first:31054.01 09:29:03 INFO - PROCESS | 1928 | Values: 671.2 698.7 703.9 706.2 712.3 15714.6 16576.3 16589.4 16717.6 16931.0 15033.3 15342.8 15484.1 15610.0 15960.0 854.7 884.3 889.3 890.1 890.8 697.0 722.2 855.4 873.4 874.0 923.2 924.0 924.1 926.5 927.3 908.8 916.6 916.6 917.1 917.4 676.9 680.2 684.3 684.8 686.6 13751.9 13870.2 13904.7 13993.8 14411.8 11699.8 13458.9 13623.9 13675.0 13788.9 11118.8 11285.3 11590.0 11631.5 11759.5 3669.7 3701.3 3738.7 3882.3 3923.7 92668.0 93543.0 93728.5 95759.5 96307.0 10302.3 10460.8 10504.9 10526.2 10651.3 20370.1 20449.8 20568.0 20721.5 20803.8 7981.8 7986.7 8048.8 8138.4 8198.3 4198.9 4378.6 4476.0 4477.4 4586.5 3368.3 3446.1 3466.0 3485.4 3490.0 640.4 644.5 648.0 656.7 659.3 599.5 660.5 661.3 671.7 683.5 7134.6 8347.0 8481.5 9207.4 9523.4 527.9 568.3 640.3 648.4 650.5 4396.2 4405.9 4589.7 4647.6 4715.1 80156.0 90862.5 112701.0 115307.0 117294.4 82734.6 97887.5 99387.0 102141.0 103092.0 88315.0 90044.5 91714.5 92454.5 93895.0 8894.4 8922.5 8934.7 8941.4 8968.9 43737.6 55318.0 57466.0 58145.4 59758.8 77582.2 81526.0 81712.6 83245.0 85014.1 11388.6 11392.9 11578.0 11583.5 11763.3 12112.6 12409.9 12564.4 12654.4 12696.7 11551.4 12297.6 12377.2 12389.6 12400.9 11271.9 11757.7 11764.5 11808.2 11988.3 10347.2 10977.0 11081.1 11083.4 11414.3 9459.5 9489.2 9490.5 9615.5 10072.8 7241.6 9174.5 9206.3 10133.1 10150.6 09:29:03 INFO - PROCESS | 1928 | -------- Summary: end -------- 09:29:03 INFO - PROCESS | 1928 | 09:29:04 INFO - TEST-INFO | 1928: exit 0 09:29:04 INFO - TEST-OK | dromaeo_css | took 1114775ms 09:29:04 INFO - TEST-START | kraken 09:29:04 INFO - operating with platform_type : mac_ 09:29:04 INFO - Initialising browser for kraken test... 09:29:04 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpp2ahIa/profile http://localhost:51546/getInfo.html) 09:29:07 INFO - PROCESS | 1936 | 2017-02-24 09:29:07.080 plugin-container[1938:15308] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:29:07 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:07 INFO - PROCESS | 1936 | 2017-02-24 09:29:07.080 plugin-container[1938:15308] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 09:29:07 INFO - PROCESS | 1936 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:07 INFO - PROCESS | 1936 | 2017-02-24 09:29:07.080 plugin-container[1938:15308] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:29:07 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 09:29:07 INFO - PROCESS | 1936 | colorDepth:24 09:29:07 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 09:29:07 INFO - PROCESS | 1936 | __metrics 09:29:07 ERROR - PROCESS | 1936 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 09:29:08 INFO - TEST-INFO | 1936: exit 0 09:29:08 INFO - Browser initialized. 09:29:08 INFO - Running cycle 1/1 for kraken test... 09:29:08 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/T/tmpp2ahIa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:10 INFO - PROCESS | 1939 | 2017-02-24 09:29:10.512 plugin-container[1940:15424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:29:10 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:10 INFO - PROCESS | 1939 | 2017-02-24 09:29:10.513 plugin-container[1940:15424] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 09:29:10 INFO - PROCESS | 1939 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:10 INFO - PROCESS | 1939 | 2017-02-24 09:29:10.513 plugin-container[1940:15424] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:29:20 INFO - PROCESS | 1939 | RSS: Main: 177356800 09:29:20 INFO - PROCESS | 1939 | 09:29:23 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51546/tests/kraken/driver.html?testName=audio-beat-detection) 09:29:24 INFO - PROCESS | 1939 | RSS: Main: 190427136 09:29:24 INFO - PROCESS | 1939 | 09:29:26 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51546/tests/kraken/driver.html?testName=audio-dft) 09:29:27 INFO - PROCESS | 1939 | RSS: Main: 198356992 09:29:27 INFO - PROCESS | 1939 | 09:29:30 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51546/tests/kraken/driver.html?testName=audio-fft) 09:29:31 INFO - PROCESS | 1939 | RSS: Main: 202022912 09:29:31 INFO - PROCESS | 1939 | 09:29:33 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51546/tests/kraken/driver.html?testName=audio-oscillator) 09:29:34 INFO - PROCESS | 1939 | RSS: Main: 203300864 09:29:34 INFO - PROCESS | 1939 | 09:29:36 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51546/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:29:36 INFO - PROCESS | 1939 | RSS: Main: 203292672 09:29:36 INFO - PROCESS | 1939 | 09:29:39 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51546/tests/kraken/driver.html?testName=imaging-darkroom) 09:29:40 INFO - PROCESS | 1939 | RSS: Main: 207306752 09:29:40 INFO - PROCESS | 1939 | 09:29:43 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51546/tests/kraken/driver.html?testName=imaging-desaturate) 09:29:44 INFO - PROCESS | 1939 | RSS: Main: 206483456 09:29:44 INFO - PROCESS | 1939 | 09:29:46 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51546/tests/kraken/driver.html?testName=json-parse-financial) 09:29:47 INFO - PROCESS | 1939 | RSS: Main: 206610432 09:29:47 INFO - PROCESS | 1939 | 09:29:49 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51546/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:49 INFO - PROCESS | 1939 | RSS: Main: 206008320 09:29:49 INFO - PROCESS | 1939 | 09:29:51 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:29:52 INFO - PROCESS | 1939 | RSS: Main: 206086144 09:29:52 INFO - PROCESS | 1939 | 09:29:54 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:29:55 INFO - PROCESS | 1939 | RSS: Main: 206082048 09:29:55 INFO - PROCESS | 1939 | 09:29:58 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:29:58 INFO - PROCESS | 1939 | RSS: Main: 206307328 09:29:58 INFO - PROCESS | 1939 | 09:30:01 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:30:02 INFO - PROCESS | 1939 | RSS: Main: 206364672 09:30:02 INFO - PROCESS | 1939 | 09:30:03 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:51546/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51546/tests/kraken/driver.html?testName=ai-astar) 09:30:04 INFO - PROCESS | 1939 | RSS: Main: 208375808 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | __start_tp_report 09:30:04 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 09:30:04 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 09:30:04 INFO - PROCESS | 1939 | |i|pagename|runs| 09:30:04 INFO - PROCESS | 1939 | |0;ai-astar;108;121;138;117;125;95;133;169;169;118 09:30:04 INFO - PROCESS | 1939 | |1;audio-beat-detection;138;142;153;164;112;109;98;93;117;122 09:30:04 INFO - PROCESS | 1939 | |2;audio-dft;203;203;195;201;200;194;223;188;210;269 09:30:04 INFO - PROCESS | 1939 | |3;audio-fft;81;58;68;113;52;69;83;86;105;82 09:30:04 INFO - PROCESS | 1939 | |4;audio-oscillator;85;70;91;83;95;70;88;90;101;120 09:30:04 INFO - PROCESS | 1939 | |5;imaging-gaussian-blur;100;73;135;83;80;116;134;129;88;90 09:30:04 INFO - PROCESS | 1939 | |6;imaging-darkroom;96;95;94;95;106;106;95;142;96;140 09:30:04 INFO - PROCESS | 1939 | |7;imaging-desaturate;88;89;86;88;92;88;91;132;138;89 09:30:04 INFO - PROCESS | 1939 | |8;json-parse-financial;57;59;57;57;60;60;62;60;62;61 09:30:04 INFO - PROCESS | 1939 | |9;json-stringify-tinderbox;66;75;68;78;78;72;68;67;70;70 09:30:04 INFO - PROCESS | 1939 | |10;stanford-crypto-aes;83;80;83;80;84;82;83;85;101;94 09:30:04 INFO - PROCESS | 1939 | |11;stanford-crypto-ccm;163;145;150;136;149;152;167;146;198;167 09:30:04 INFO - PROCESS | 1939 | |12;stanford-crypto-pbkdf2;165;146;218;179;176;164;162;166;166;165 09:30:04 INFO - PROCESS | 1939 | |13;stanford-crypto-sha256-iterative;63;75;81;74;68;66;68;68;67;74 09:30:04 INFO - PROCESS | 1939 | __end_tp_report 09:30:04 INFO - PROCESS | 1939 | __start_cc_report 09:30:04 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,950 09:30:04 INFO - PROCESS | 1939 | __end_cc_report 09:30:04 INFO - PROCESS | 1939 | __startTimestamp1487957404259__endTimestamp 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | ------- Summary: start ------- 09:30:04 INFO - PROCESS | 1939 | Number of tests: 14 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#0] ai-astar Cycles:10 Average:129.30 Median:135.50 stddev:24.12 (17.8%) stddev-sans-first:24.33 09:30:04 INFO - PROCESS | 1939 | Values: 108.0 121.0 138.0 117.0 125.0 95.0 133.0 169.0 169.0 118.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:147.50 stddev:23.60 (16.0%) stddev-sans-first:24.55 09:30:04 INFO - PROCESS | 1939 | Values: 138.0 142.0 153.0 164.0 112.0 109.0 98.0 93.0 117.0 122.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#2] audio-dft Cycles:10 Average:208.60 Median:203.00 stddev:23.27 (11.5%) stddev-sans-first:24.60 09:30:04 INFO - PROCESS | 1939 | Values: 203.0 203.0 195.0 201.0 200.0 194.0 223.0 188.0 210.0 269.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#3] audio-fft Cycles:10 Average:79.70 Median:75.00 stddev:19.14 (25.5%) stddev-sans-first:20.29 09:30:04 INFO - PROCESS | 1939 | Values: 81.0 58.0 68.0 113.0 52.0 69.0 83.0 86.0 105.0 82.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:86.50 stddev:14.61 (16.9%) stddev-sans-first:15.41 09:30:04 INFO - PROCESS | 1939 | Values: 85.0 70.0 91.0 83.0 95.0 70.0 88.0 90.0 101.0 120.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#5] imaging-gaussian-blur Cycles:10 Average:102.80 Median:76.50 stddev:23.71 (31.0%) stddev-sans-first:25.13 09:30:04 INFO - PROCESS | 1939 | Values: 100.0 73.0 135.0 83.0 80.0 116.0 134.0 129.0 88.0 90.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#6] imaging-darkroom Cycles:10 Average:106.50 Median:95.00 stddev:18.73 (19.7%) stddev-sans-first:19.47 09:30:04 INFO - PROCESS | 1939 | Values: 96.0 95.0 94.0 95.0 106.0 106.0 95.0 142.0 96.0 140.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#7] imaging-desaturate Cycles:10 Average:98.10 Median:88.50 stddev:19.57 (22.1%) stddev-sans-first:20.41 09:30:04 INFO - PROCESS | 1939 | Values: 88.0 89.0 86.0 88.0 92.0 88.0 91.0 132.0 138.0 89.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#8] json-parse-financial Cycles:10 Average:59.50 Median:60.00 stddev:1.96 (3.3%) stddev-sans-first:1.86 09:30:04 INFO - PROCESS | 1939 | Values: 57.0 59.0 57.0 57.0 60.0 60.0 62.0 60.0 62.0 61.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:4.42 (6.2%) stddev-sans-first:4.27 09:30:04 INFO - PROCESS | 1939 | Values: 66.0 75.0 68.0 78.0 78.0 72.0 68.0 67.0 70.0 70.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:83.00 stddev:6.72 (8.1%) stddev-sans-first:7.07 09:30:04 INFO - PROCESS | 1939 | Values: 83.0 80.0 83.0 80.0 84.0 82.0 83.0 85.0 101.0 94.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#11] stanford-crypto-ccm Cycles:10 Average:157.30 Median:157.50 stddev:17.51 (11.1%) stddev-sans-first:18.45 09:30:04 INFO - PROCESS | 1939 | Values: 163.0 145.0 150.0 136.0 149.0 152.0 167.0 146.0 198.0 167.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.70 Median:166.00 stddev:18.78 (11.3%) stddev-sans-first:19.81 09:30:04 INFO - PROCESS | 1939 | Values: 165.0 146.0 218.0 179.0 176.0 164.0 162.0 166.0 166.0 165.0 09:30:04 INFO - PROCESS | 1939 | 09:30:04 INFO - PROCESS | 1939 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:5.40 (7.6%) stddev-sans-first:5.02 09:30:04 INFO - PROCESS | 1939 | Values: 63.0 75.0 81.0 74.0 68.0 66.0 68.0 68.0 67.0 74.0 09:30:04 INFO - PROCESS | 1939 | -------- Summary: end -------- 09:30:04 INFO - PROCESS | 1939 | 09:30:05 INFO - TEST-INFO | 1939: exit 0 09:30:05 INFO - TEST-OK | kraken | took 61181ms 09:30:05 INFO - SUITE-END | took 1175s 09:30:05 INFO - Completed test suite (00:19:36) 09:30:05 INFO - javascript benchmark 09:30:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7280.55655311672, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3534.9293014139716, 3546.521219635347, 3561.679448849603, 3579.9463008054877, 3598.4242521196616, 6004.669743164124, 6033.969830150849, 6035.486983606399, 6046.969765151173, 6066.7573297068175, 4996.725180115092, 6024.487918526919, 6110.327863934975, 6112.724927378267, 6120.0, 5395.379531353896, 5487.807926722566, 5494.313210848644, 5498.477644623755, 5645.717714114298, 5558.583106267026, 5568.832935011957, 5570.192322113311, 5580.190872323511, 5584.21820945068, 5434.7826086956475, 5435.592330575203, 5493.862653433676, 5540.944590554103, 5550.056490396642, 4389.363542286383, 5470.9452905470835, 5508.173773933922, 5536.750846211889, 5540.058190107651, 5337.466253374631, 5455.672659640394, 5465.508104270635, 5488.121900495941, 5523.171524271352, 5851.23933888596, 6071.33215346316, 6092.51259899207, 6127.89697854386, 6127.969360153171, 6227.750889964442, 6229.0, 6238.532110091761, 6260.311365749764, 6265.7493700251935, 6329.6202227866015, 6349.36506349366, 6371.904421433682, 6419.967900160516, 6420.165378500812, 6109.5723299369065, 6125.509959203253, 6136.447719705248, 6141.293751218667, 6144.293406258248, 6379.872402551923, 6398.9040164397575, 6440.227172739301, 6471.255805582418, 6518.315576864361, 6303.621782693053, 6314.747410103636, 6320.747170113236, 6324.747010119636, 6331.746730130743, 5368.731563421813, 6160.568760186834, 6356.523260755462, 6371.299157092716, 6390.201224846895, 6216.844578885564, 6265.624062556261, 6278.748850045947, 6278.780242691484, 6303.936960630427, 6233.657148856856, 6307.180066591313, 6314.0844577536, 6323.620582764956, 6329.398707122816, 5124.026434977342, 5162.896742065138, 5163.845084647466, 5169.276301317818, 5180.300659411007, 4548.522405147478, 4568.132054909556, 4589.931151032737, 4621.560951709582, 4647.790849411785, 5468.507834294933, 5965.582409231399, 6085.056816193497, 6145.078238264206, 6161.630302181883, 6364.84087897797, 6364.936350636527, 6378.840528986812, 6388.169537960035, 6398.1362516059735, 6022.608530445507, 6097.146399504073, 6097.207363042776, 6123.2652081750475, 6132.93684452123, 5089.185730283137, 5619.6347237430255, 5661.575381846295, 5662.462066103713, 5700.00249956256, 6569.014647802868, 6606.966965165239, 6628.569143005786, 6631.469482441489, 6656.7337306509135, 6195.287541932736, 6253.6560489173535, 6290.559660823698, 6354.0, 6394.104825324552, 6582.210134783665, 6604.801855944329, 6608.669566521558, 6620.602763834186, 6622.933770662425, 6277.748890044347, 6307.274663413767, 6308.211473565804, 6310.5898116622275, 6328.430441260309, 6222.0, 6283.0, 6284.685765711604, 6348.301686814354, 6400.935990640034, 5450.482204190595, 6229.345918678474, 6251.53113516479, 6261.279952805304, 6272.278687950761, 6203.193584833943, 6239.188905442264, 6246.906296405648, 6265.686715664289, 6268.153799237227, 5214.800595862896, 6100.511959043177, 6108.0688921796145, 6232.25212974446, 6256.249250090018, 6192.473639740492, 6200.224971878515, 6212.4408803207925, 6289.874202516015, 6304.590201636879, 6242.469390101892, 6256.812295631138, 6264.31092579825, 6280.120783090462, 6280.497560195082], "value": 5894.590800979601, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.4727418025623, 507.949205079492, 515.0805811626246, 526.4261954469628, 529.700719093712, 38010.23979520408, 40766.7961660192, 40830.18339633209, 40892.38661420074, 41015.974600634996, 42371.0, 42991.78504107483, 43002.78498607503, 43078.56921430785, 43135.0, 9534.475603841785, 9697.95151024244, 9725.659601913934, 9738.0, 9745.171660408874, 8522.275606573448, 9627.181689556395, 9716.222702183844, 9720.659776907809, 9739.026097390275, 9663.75840603987, 9680.322377433582, 9746.122848943594, 9751.561179746928, 9761.804763904718, 8404.957975210116, 9646.565904534313, 9732.026797320212, 9749.26880483963, 9752.658656947044, 7736.535807851491, 9617.086376794194, 9643.08390702882, 9699.030096990315, 9703.951480242624, 39680.801595992125, 39773.80678579647, 40512.79743601263, 40537.78386648405, 40613.39079913804, 3237.0735978983457, 3394.389009978203, 3420.0, 3426.8971930842113, 4052.1895620875944, 29723.959661411962, 29762.0, 30295.939642112422, 30921.381572368653, 32013.039608811774, 3188.6970737779875, 3332.9333413331597, 3336.699697027255, 3407.4718418645148, 3442.294329662413, 416598.0, 496753.0, 503305.0, 504448.0, 505943.4702826499, 62305.68847155826, 62415.68792156101, 62571.374286257465, 62609.74780504365, 62688.68655656784, 65039.0, 65255.67372163109, 65672.6716366415, 65955.67022164859, 65974.34025659777, 16585.585360365847, 16607.667846643242, 16860.494185174466, 17077.658446830992, 17109.315627375014, 12826.67933301675, 13138.0, 13156.40796164175, 13233.20600763938, 13254.0, 16947.491575252763, 17237.56906077333, 17410.39063632767, 17489.86315889463, 17516.912415438095, 6267.2165979252595, 6289.559730818799, 6360.1731774869895, 6429.0, 6436.098946147543, 846.551327796269, 942.6041062753523, 949.7530642033121, 976.3799987008159, 976.3799987008301, 30116.849415752782, 30254.0, 30313.787448502255, 30488.0, 30863.228419289248, 837.8437755896823, 905.7780843693206, 1011.5397494140149, 1034.4672493665769, 1099.0603034405628, 13175.736485270241, 15467.530584594446, 15502.612434689223, 15722.921385393, 15982.76025859613, 632965.0, 634328.0, 643333.0, 644595.0, 646342.0, 58102.41897581055, 58583.70708146432, 58595.41404585985, 58753.4124658748, 58984.410155897895, 1355.7424089422977, 1391.464286249801, 1396.694091024895, 1403.1721284442228, 1406.0, 29340.266493337836, 29895.32754915203, 29976.0, 30030.24924376908, 30382.696173037988, 50886.49113508966, 51160.976780464924, 51183.72040699012, 51460.485395147065, 51526.22710659418, 512744.0, 561397.1930140486, 563630.0, 567438.0, 570318.0, 40113.1977360457, 41247.0, 41908.58091419169, 42168.578314216466, 42267.57732422637, 45825.77087114543, 46826.53173468222, 47126.0, 48471.75764121157, 48618.75690621524, 32048.51927221047, 38278.0, 38868.0, 38880.2223955525, 40293.0, 37229.6277037237, 37879.05249973895, 38221.0, 38504.614953851225, 38724.80637596794, 34575.65424345724, 37784.24431511299, 39147.0, 39575.81272561828, 40241.79274621766, 31634.525482118246, 32958.0, 33782.83108584442, 33947.32105357928, 34140.29298535113, 21617.958492164587, 25697.614535781606, 25810.0, 26063.478730424908, 27332.726672733017], "value": 20108.049786554308, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.98468061277566, 384.7460675953871, 387.5620548779878, 394.14077311461006, 416.41493701349617, 19120.808791912077, 20452.795472045258, 20513.38459846203, 20552.38342849717, 21020.36938891832, 19992.901455201685, 20045.298414555495, 20550.69173962388, 20819.791802081974, 20851.791482085173, 5653.378128405882, 6100.0, 6116.602420842653, 6157.014877619582, 6173.845653858667, 5753.942460575383, 5894.9978503654265, 5970.522358211354, 6062.4543791058795, 6074.331823499411, 5918.348981612019, 5993.88012239755, 5996.700164991733, 6000.969995150018, 6013.729382177812, 5871.70641467927, 5966.40335966402, 5989.64062156272, 6232.470240029603, 6599.274079851212, 5846.532277417797, 5891.05743081105, 5969.611975221596, 5975.163477113208, 5988.071848863433, 23904.402389940216, 24304.39239019021, 24698.382540436454, 25032.624510632355, 25205.0, 2148.6347320955474, 2180.2805074325593, 2300.068472268717, 2327.8254130940077, 2559.462512872299, 10035.193665140272, 10304.587816487407, 10336.224783141295, 10358.741031474274, 13255.740704633045, 1843.2813191292591, 1865.412395095554, 1872.7565416495768, 1889.527618095476, 1925.1817977359706, 86169.0, 112968.87031129666, 114535.42732286286, 114633.0, 114665.85334146635, 9460.668876589423, 10358.378497290187, 10362.689119326433, 10897.346159230472, 10954.39750813697, 12939.417726202344, 13052.477900884049, 13064.150830196008, 13067.0, 13072.869271307165, 8178.182181781894, 8326.084130745614, 8346.207110324509, 8442.915570844212, 8482.533460659723, 8531.976162860496, 8555.315574754004, 8577.228049475578, 8719.694810681596, 8770.736877893672, 3514.8594056237466, 4095.119549296856, 4140.0, 4175.827433387847, 4213.346931225665, 1920.7887132415424, 1967.537628657293, 1971.960560788805, 1972.7928567500205, 1973.6348775476583, 824.5135370131651, 832.8750687396939, 896.3098414221014, 954.7279025477706, 991.3407583956588, 20525.38423847287, 20970.895145524173, 21116.78883211148, 21337.573248534944, 21395.46511337229, 774.9612519374009, 919.9448033118034, 936.8547875079373, 939.7979434421633, 947.4362754161263, 9192.862107068398, 10634.255602107933, 11105.444727763579, 11132.443377831076, 11196.664100076849, 534243.0, 580899.0, 583163.0, 592225.0, 624692.0, 85807.0, 126593.0, 126953.0, 133495.0, 137304.0, 846.6824940647257, 935.6444551070549, 991.1723710701501, 993.8260804359209, 994.2493417469764, 17971.0, 18256.90871545687, 18604.44186674402, 18681.252749890395, 18833.90583047076, 53251.0, 53369.733151333996, 54585.454145459626, 55811.0, 55945.0, 53920.0, 72118.27881721116, 73415.0, 73869.6306518464, 75808.0, 4907.067657145131, 5051.772670229922, 7905.656038473358, 7916.327112195528, 8142.83714325722, 10372.636957706443, 12152.331621760888, 12499.312537810507, 12553.184043037174, 12765.361731913554, 10683.57491543401, 11197.160212984221, 11363.48864301125, 11408.258463199865, 11524.250923689933, 9364.531773411167, 10851.728706782078, 11040.392778397265, 11107.000369966741, 11141.777164456504, 11059.115270778479, 11195.160362972973, 11238.719032024264, 11430.25703329281, 11593.536258549564, 8143.714969976022, 8188.713395031145, 8195.303399211134, 8229.423940324119, 8409.789755256168, 7924.841503169789, 10758.193135514712, 11220.1023918088, 11326.943365283121, 11341.489632966372], "value": 9393.060682969071, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.6773842016335, 1471.3673120558162, 1656.4450908945503, 1665.8084320303162, 1801.6576850398415, 7493.9250607493905, 7700.383969282458, 8020.518768873864, 8468.19552142547, 8478.830423391537, 7939.245771651684, 8215.548144852031, 8234.21774931382, 8312.168783121684, 8355.164483551642, 2343.238447504559, 2353.105819788478, 2364.9645255321184, 2369.1944738788807, 2373.7982271506858, 2289.3246492284734, 2341.84777989431, 2383.2731017039796, 2402.8678422686744, 2404.699412573428, 1953.5213872601232, 2154.278316763886, 2372.181597348912, 2378.3340664613847, 2455.8035357171384, 2327.2203811723093, 2328.1967721136266, 2355.3287313115766, 2355.729091154505, 2356.587597170497, 2354.0466111224982, 2375.2874137758777, 2387.5224955009066, 2389.569877422063, 2397.44858682504, 8329.791755206108, 8330.0, 8408.159184081604, 8435.156484351577, 8483.321334293241, 5055.368078990126, 5109.770060347293, 5235.581153507748, 5291.126964050927, 5294.947050529484, 3399.8130102844334, 3491.4064609016527, 3548.183917698944, 3629.872954446582, 3636.799976001319, 1050.3435352904435, 1071.9571217151304, 1115.3530952047793, 1121.5121422181378, 1126.808442564766, 10817.242793004492, 10868.347899126078, 10882.347059176474, 10917.290376125606, 10919.563217471292, 4478.261086920622, 4678.508756580578, 4772.093302272557, 4782.187028205144, 4783.521647835259, 9198.632054717737, 9372.765680857896, 9377.1091746284, 9379.812403751886, 9423.38747981379, 4583.702059366197, 4985.626078044161, 5228.816991405359, 5231.581473482189, 5246.947530524722, 4322.762248076386, 4398.846040388635, 4451.488078870972, 4649.651276154234, 4748.430188377417, 3310.2220978070136, 3844.3464611016193, 3922.6466868930174, 3949.9012524686536, 4012.658923991435, 1134.387430787382, 1190.7972947323092, 1250.9812352814715, 1295.9287239201935, 1307.5096838685492, 759.5632511306021, 863.6027427383474, 913.2055112052424, 915.1260546178383, 922.9123233292827, 4426.712263702849, 5621.522170615462, 5669.40471250521, 5694.4590263924865, 5733.3406658234835, 907.577976241051, 938.2212763406357, 954.8376775948105, 966.6954909203579, 976.4239098931522, 3924.548676902136, 3955.773710149805, 4075.673946084306, 4106.055607210299, 4163.167366526707, 8865.379423440427, 9819.216571980134, 9951.900480995097, 10139.188864890792, 10140.340877842917, 9878.0, 9948.403095814274, 10111.0, 10134.797304053878, 10151.086402223835, 7756.612169391507, 7807.921920780833, 7838.960805196051, 7847.921520784833, 7925.76222713308, 5779.0, 5881.852953676193, 5917.053271476543, 5993.460588547052, 6026.6082704625815, 9198.264138869008, 9201.217896478875, 9335.953320233355, 9356.812863742824, 9390.295727820305, 8948.373613847229, 9309.581068851783, 9321.86017209756, 9416.10546998034, 9418.858717119112, 6602.504812139013, 6775.762848300285, 6911.89632155508, 7082.787516374517, 7234.63826808668, 7712.460127791001, 7976.962994810638, 7978.0, 7983.401244906771, 8013.0, 6319.3364696706185, 7007.159140903124, 7088.503804733619, 7105.786826395217, 7112.431005519443, 6185.443310102112, 6857.965710171417, 7023.789286321418, 7042.507024508235, 7101.0, 6599.109120268702, 6747.291534388818, 6760.594364338155, 6761.797146085625, 6789.966050169717, 4602.815887364468, 4837.95162048375, 4909.631777616622, 4920.8769780755765, 4922.655414120977, 4839.516048395132, 4855.635827312895, 4959.652824302264, 4967.4287456941465, 4994.80020799164], "value": 4435.110273903605, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.8347275943749, 314.81897908702496, 324.54077480365277, 325.0978534566579, 328.2663247641521, 4379.934300985484, 5233.476652334764, 5376.258108364743, 5493.807716729915, 5515.8692468044055, 5104.6937183769, 5172.896542069157, 5243.86890327742, 5334.973325133369, 5347.866303342418, 3456.308738252347, 4107.527634322047, 4145.502539695241, 4152.854650087247, 4163.83344666213, 3185.012646079723, 3567.9108022299392, 4018.0557568971267, 4133.91732165358, 4179.0, 4092.3043082676018, 4094.7133700640966, 4096.947773908442, 4098.8192044114985, 4129.9174016519655, 3567.8751243706474, 4089.5910408959016, 4110.23960567296, 4111.773852438115, 4131.235721391552, 3614.9332001498315, 3628.092976755811, 3633.3096711624707, 3641.8543258269638, 3652.5799533053773, 4413.823447062114, 4694.201985662446, 4941.579965702919, 5119.462456442057, 5259.8434641796675, 4748.145333839908, 4755.262934245197, 4778.4265888093305, 4781.569658730731, 4783.976080119612, 4768.0, 5583.687833359158, 5670.716464176775, 5686.289213848269, 5746.798862039808, 3227.8708851645906, 3641.890743277706, 3648.8175591220597, 3858.3247931611854, 3993.7204395692315, 5728.169415434781, 5995.52035837132, 6004.819855404345, 6018.127371531147, 6067.787627433063, 3398.2329188821673, 3548.0, 3935.897948574404, 3989.4614227079555, 4016.6987475939422, 3502.614712381636, 3663.0, 3780.164876342743, 3923.4114882767813, 4065.2682517147196, 3950.7036972226624, 4173.436586060843, 4655.627549796008, 4659.184642687516, 4688.578027977498, 4822.71063736177, 5429.56563474921, 5780.092569452071, 5824.94175058244, 5849.61977471463, 1257.1953949472345, 1267.9032636769314, 1281.8525869524938, 1287.7102651903247, 1307.6338625184962, 775.87110753853, 870.4636316900676, 892.9866052009226, 893.2942975049638, 895.0, 4516.706414083074, 5127.102757017507, 5139.383274007143, 5141.742912854343, 5184.7926082956255, 5220.686758794484, 5616.663000219918, 5618.887622247532, 5636.689982050944, 5670.639046628779, 4636.605888499448, 4692.897169165243, 4861.51384861511, 5130.4356520782685, 5139.177731562932, 2711.701712811589, 2751.4179346875626, 2857.614057182265, 2902.4050069735654, 2951.881924722987, 4974.751262436864, 5042.613281347585, 5779.797707080316, 5789.205346342647, 5804.543728894825, 5669.971650141723, 6034.396560344018, 6097.634141951497, 6116.418940200674, 6118.43025929102, 5485.5189098943065, 5923.4076592340425, 6260.499160067184, 6302.968485157545, 6311.810645680637, 5930.792422265373, 6025.156478092983, 6033.276006879027, 6046.0, 6049.546284028627, 4732.739699316433, 5736.483716465538, 5966.582339236212, 6016.465801220661, 6027.879442411215, 4880.633952453509, 5608.6073974821375, 5917.408259174221, 5974.312954010345, 6088.908666370096, 3984.5019372578427, 3997.760134391946, 4011.298022845932, 4027.8791636250958, 4037.9596204037584, 5866.853328666817, 6033.095035744674, 6077.392260773887, 6087.147799308189, 6092.390760923872, 5696.177223288673, 5701.092353525322, 5705.572082093942, 5765.4811067004175, 5865.3254875689845, 4860.708357498562, 5089.567386772166, 5412.83761487156, 5854.765809367577, 5894.823155305347, 4670.859874203779, 5265.578753699619, 5447.6769170806565, 5571.470710282517, 5659.9151012735665, 5827.94172058274, 5849.303701926394, 5878.970605146947, 5884.382139875423, 5912.201852749824, 4836.540528649773, 5303.840884773463, 5322.281491998686, 5525.320219693365, 5654.943450565606], "value": 4141.290179865364, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.1744554198333, 698.6890833579057, 703.9155301363837, 706.1808302369253, 712.2805965974366, 15714.607134821636, 16576.33694652214, 16589.419370322026, 16717.582060448494, 16931.0, 15033.323500442479, 15342.769858452131, 15484.070955742664, 15609.98535095218, 15960.0, 854.7393045121252, 884.2748945864394, 889.3241136736095, 890.1143362354467, 890.8262888736699, 696.9930300696979, 722.2452537098744, 855.3841234311317, 873.4322690251356, 874.0210963720642, 923.1968187676697, 924.0066928052337, 924.1313165624325, 926.4996901673122, 927.3184209605935, 908.7955210077746, 916.573793186165, 916.5875356089787, 917.1104029091804, 917.3899356927635, 676.9018492318636, 680.1905732178741, 684.3498676257525, 684.832216107057, 686.584616307132, 13751.862481375158, 13870.237136957463, 13904.721905561832, 13993.790093148611, 14411.78382324266, 11699.824502632468, 13458.932705336509, 13623.931880340633, 13675.042747007714, 13788.93105534476, 11118.833217501744, 11285.266457680225, 11590.014848737868, 11631.53473861053, 11759.47082381295, 3669.669729724311, 3701.3152566774966, 3738.738288319819, 3882.3400021996326, 3923.6861051115843, 92668.0, 93543.0, 93728.53135734345, 95759.52120239355, 96307.0, 10302.330348527472, 10460.790784184426, 10504.947475262576, 10526.157907367393, 10651.254412191227, 20370.083346249456, 20449.795502044788, 20567.971601419868, 20721.481962950744, 20803.79196208049, 7981.800454988672, 7986.720464783704, 8048.798780030429, 8138.3896207784655, 8198.262156405834, 4198.895027624273, 4378.605925466723, 4475.977620111944, 4477.3731677565165, 4586.518415566317, 3368.2758206985613, 3446.138465383654, 3465.965340346564, 3485.390056740111, 3490.0402389342626, 640.3724350136891, 644.452215616722, 648.007377314759, 656.7340227207989, 659.3241927024742, 599.5323647554915, 660.4914216053612, 661.3221448015822, 671.717878491035, 683.5249501596442, 7134.643267836587, 8346.956630421197, 8481.491110533389, 9207.447553146832, 9523.380980236263, 527.9498447647468, 568.3095039526994, 640.3340525853154, 648.4455790299227, 650.54786923088, 4396.208682437128, 4405.933910991338, 4589.724616523019, 4647.62818974488, 4715.104130215248, 80156.0, 90862.54568727246, 112701.0, 115307.0, 117294.41352793352, 82734.58632706918, 97887.53168702475, 99387.01838944844, 102141.0, 103092.0, 88315.0, 90044.5497772507, 91714.54142729244, 92454.53772731093, 93895.0, 8894.377393582387, 8922.464652120894, 8934.687285944961, 8941.37410381267, 8968.910310896808, 43737.56262437335, 55318.0, 57466.0, 58145.41854581569, 59758.75439718497, 77582.2241777575, 81526.0, 81712.59143704243, 83245.0, 85014.14985850062, 11388.601398950997, 11392.94303528477, 11578.0, 11583.536658533565, 11763.294202347886, 12112.636620901387, 12409.866983822274, 12564.37178141071, 12654.430550625428, 12696.74606507883, 11551.364674942623, 12297.569585064837, 12377.195482293622, 12389.62831115068, 12400.945919596574, 11271.887281127412, 11757.706057348634, 11764.470598823245, 11808.232464889754, 11988.280703157838, 10347.223958203014, 10977.0, 11081.058110060736, 11083.390413527333, 11414.258073225546, 9459.479728614991, 9489.1934185595, 9490.525473726424, 9615.51922403863, 10072.798544029225, 7241.565506069653, 9174.495402752913, 9206.309526785384, 10133.08802207805, 10150.593976240867], "value": 7282.334654181108, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 138.0, 117.0, 125.0, 95.0, 133.0, 169.0, 169.0, 118.0], "value": 129.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 153.0, 164.0, 112.0, 109.0, 98.0, 93.0, 117.0, 122.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 203.0, 195.0, 201.0, 200.0, 194.0, 223.0, 188.0, 210.0, 269.0], "value": 208.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 68.0, 113.0, 52.0, 69.0, 83.0, 86.0, 105.0, 82.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 91.0, 83.0, 95.0, 70.0, 88.0, 90.0, 101.0, 120.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 73.0, 135.0, 83.0, 80.0, 116.0, 134.0, 129.0, 88.0, 90.0], "value": 102.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 95.0, 106.0, 106.0, 95.0, 142.0, 96.0, 140.0], "value": 106.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 86.0, 88.0, 92.0, 88.0, 91.0, 132.0, 138.0, 89.0], "value": 98.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 57.0, 60.0, 60.0, 62.0, 60.0, 62.0, 61.0], "value": 59.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 68.0, 78.0, 78.0, 72.0, 68.0, 67.0, 70.0, 70.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 83.0, 80.0, 84.0, 82.0, 83.0, 85.0, 101.0, 94.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 145.0, 150.0, 136.0, 149.0, 152.0, 167.0, 146.0, 198.0, 167.0], "value": 157.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 146.0, 218.0, 179.0, 176.0, 164.0, 162.0, 166.0, 166.0, 165.0], "value": 170.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 75.0, 81.0, 74.0, 68.0, 66.0, 68.0, 68.0, 67.0, 74.0], "value": 70.4, "unit": "score"}]}]} 09:30:05 INFO - Return code: 0 09:30:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:30:05 ERROR - # TBPL SUCCESS # 09:30:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:05 INFO - 2017-02-24 09:30:05.592 screenresolution[1945:15832] starting screenresolution argv=screenresolution get 09:30:05 INFO - 2017-02-24 09:30:05.595 screenresolution[1945:15832] Display 0: 1600x1200x32@60 09:30:05 INFO - 2017-02-24 09:30:05.601 screenresolution[1946:15835] starting screenresolution argv=screenresolution list 09:30:05 INFO - Available Modes on Display 0 09:30:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:05 INFO - Intel Iris: 09:30:05 INFO - Chipset Model: Intel Iris 09:30:05 INFO - Type: GPU 09:30:05 INFO - Bus: Built-In 09:30:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:05 INFO - Vendor: Intel (0x8086) 09:30:05 INFO - Device ID: 0x0a2e 09:30:05 INFO - Revision ID: 0x0009 09:30:05 INFO - Displays: 09:30:05 INFO - Display: 09:30:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:05 INFO - Display Serial Number: 1600x1200 60 09:30:05 INFO - Main Display: Yes 09:30:05 INFO - Mirror: Off 09:30:05 INFO - Online: Yes 09:30:05 INFO - Rotation: Supported 09:30:05 INFO - Return code: 0 09:30:05 INFO - Running post-action listener: _resource_record_post_action 09:30:05 INFO - [mozharness: 2017-02-24 17:30:05.843183Z] Finished run-tests step (success) 09:30:05 INFO - Running post-run listener: _resource_record_post_run 09:30:05 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 2176000; Write bytes: 506216960; Read time: 24; Write time: 1622 09:30:05 INFO - TinderboxPrint: CPU usage
54.2% 09:30:05 INFO - TinderboxPrint: I/O read bytes / time
2,176,000 / 24 09:30:05 INFO - TinderboxPrint: I/O write bytes / time
506,216,960 / 1,622 09:30:05 INFO - TinderboxPrint: CPU idle
2,197.3 (45.8%) 09:30:05 INFO - TinderboxPrint: CPU system
965.8 (20.1%) 09:30:05 INFO - TinderboxPrint: CPU user
1,630.5 (34.0%) 09:30:05 INFO - TinderboxPrint: Swap in / out
194,621,440 / 0 09:30:05 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 179145216; Write bytes: 177899520; Read time: 17061; Write time: 381 09:30:05 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 2176000; Write bytes: 310524416; Read time: 24; Write time: 1185 09:30:06 INFO - Running post-run listener: _upload_blobber_files 09:30:06 INFO - Blob upload gear active. 09:30:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:30:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:30:06 INFO - (blobuploader) - INFO - Open directory for files ... 09:30:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:30:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:30:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:07 INFO - (blobuploader) - INFO - Done attempting. 09:30:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:30:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:30:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:08 INFO - (blobuploader) - INFO - Done attempting. 09:30:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:30:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:30:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:10 INFO - (blobuploader) - INFO - Done attempting. 09:30:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:30:10 INFO - Return code: 0 09:30:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:30:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:30:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/67e3b876d1518011d1958e1b52eccc63e99dec3efcab33a85d07676904b5cf6db1cf4a5e6c33330a766e0048b455bdcf5ab5b1b002e5e48f5f17bf380efc88bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ac5f8d84fe98038e97e3adc88a0539cf75a1bec1058d0e8765b2bdd703218bdc1d65024540d2b783cd3c9faebeae7fe98ab6e46207a25ee86f13c218e5d6f1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3211cdd64e7c740c4dc58215e43c3b16e9c90ba6bdceaeb6753edac3a3e77ad2dbb9cf016fa0d47bac77161a0e79231e8fc2d27c7a193c7030912de4559d58a"} 09:30:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:30:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:30:10 INFO - Contents: 09:30:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/67e3b876d1518011d1958e1b52eccc63e99dec3efcab33a85d07676904b5cf6db1cf4a5e6c33330a766e0048b455bdcf5ab5b1b002e5e48f5f17bf380efc88bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ac5f8d84fe98038e97e3adc88a0539cf75a1bec1058d0e8765b2bdd703218bdc1d65024540d2b783cd3c9faebeae7fe98ab6e46207a25ee86f13c218e5d6f1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3211cdd64e7c740c4dc58215e43c3b16e9c90ba6bdceaeb6753edac3a3e77ad2dbb9cf016fa0d47bac77161a0e79231e8fc2d27c7a193c7030912de4559d58a"} 09:30:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:30:10 INFO - Copying logs to upload dir... 09:30:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:30:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.704760 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-24 09:30:10.998686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 09:30:11.002358) ========= 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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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/Mozilla-Beta/sha512/67e3b876d1518011d1958e1b52eccc63e99dec3efcab33a85d07676904b5cf6db1cf4a5e6c33330a766e0048b455bdcf5ab5b1b002e5e48f5f17bf380efc88bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ac5f8d84fe98038e97e3adc88a0539cf75a1bec1058d0e8765b2bdd703218bdc1d65024540d2b783cd3c9faebeae7fe98ab6e46207a25ee86f13c218e5d6f1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3211cdd64e7c740c4dc58215e43c3b16e9c90ba6bdceaeb6753edac3a3e77ad2dbb9cf016fa0d47bac77161a0e79231e8fc2d27c7a193c7030912de4559d58a"} build_url:https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011029 build_url: 'https://queue.taskcluster.net/v1/task/XIB7u4nFRqagXQ3-fiptWg/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/67e3b876d1518011d1958e1b52eccc63e99dec3efcab33a85d07676904b5cf6db1cf4a5e6c33330a766e0048b455bdcf5ab5b1b002e5e48f5f17bf380efc88bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ac5f8d84fe98038e97e3adc88a0539cf75a1bec1058d0e8765b2bdd703218bdc1d65024540d2b783cd3c9faebeae7fe98ab6e46207a25ee86f13c218e5d6f1e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f3211cdd64e7c740c4dc58215e43c3b16e9c90ba6bdceaeb6753edac3a3e77ad2dbb9cf016fa0d47bac77161a0e79231e8fc2d27c7a193c7030912de4559d58a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 09:30:11.039560) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:30:11.040347) ========= 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.FNeFYdeSGP/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.7hS1CdAz4H/Listeners TMPDIR=/var/folders/3m/qxqc0m_j4y9b2z26qf6v21y000000w/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.005281 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 09:30:11.090478) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 09:30:11.090754) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 09:30:11.091039) ========= ========= Total master_lag: 0.19 =========