builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1488497318.89 results: success (0) buildid: 20170302120751 builduid: 4222224de113450ab8b102b935be6319 revision: 25ee9d2ee428d3cc16ba425ad2dd74496cf1d7a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:38.891099) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:38.891584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:38.891862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:38.919358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:38.919749) ========= 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.aA8z4bia0r/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.tTVuqSiSsI/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-03-02 15:28:38-- 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% 15.0M=0.001s 2017-03-02 15:28:39 (15.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155214 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:39.099412) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:39.099865) ========= 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.aA8z4bia0r/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.tTVuqSiSsI/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.027677 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:39.151110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-02 15:28:39.151719) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 25ee9d2ee428d3cc16ba425ad2dd74496cf1d7a6 --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 25ee9d2ee428d3cc16ba425ad2dd74496cf1d7a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aA8z4bia0r/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.tTVuqSiSsI/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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-03-02 15:28:39,254 truncating revision to first 12 chars 2017-03-02 15:28:39,254 Setting DEBUG logging. 2017-03-02 15:28:39,254 attempt 1/10 2017-03-02 15:28:39,254 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/25ee9d2ee428?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-02 15:28:41,220 unpacking tar archive at: mozilla-beta-25ee9d2ee428/testing/mozharness/ program finished with exit code 0 elapsedTime=2.226281 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-02 15:28:41.402498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:41.402812) ========= script_repo_revision: 25ee9d2ee428d3cc16ba425ad2dd74496cf1d7a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:41.403225) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:41.403541) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-02 15:28:41.423867) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-03-02 15:28:41.424136) ========= /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.aA8z4bia0r/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.tTVuqSiSsI/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:28:41 INFO - MultiFileLogger online at 20170302 15:28:41 in /builds/slave/test 15:28:41 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 15:28:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:28:41 INFO - {'append_to_log': False, 15:28:41 INFO - 'base_work_dir': '/builds/slave/test', 15:28:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:28:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:28:41 INFO - 'branch': 'Mozilla-Beta', 15:28:41 INFO - 'buildbot_json_path': 'buildprops.json', 15:28:41 INFO - 'config_files': ('talos/mac_config.py',), 15:28:41 INFO - 'default_actions': ('clobber', 15:28:41 INFO - 'read-buildbot-config', 15:28:41 INFO - 'download-and-extract', 15:28:41 INFO - 'populate-webroot', 15:28:41 INFO - 'create-virtualenv', 15:28:41 INFO - 'install', 15:28:41 INFO - 'run-tests'), 15:28:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:28:41 INFO - 'download_minidump_stackwalk': True, 15:28:41 INFO - 'download_symbols': 'ondemand', 15:28:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:28:41 INFO - 'tooltool.py': '/tools/tooltool.py', 15:28:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:28:41 INFO - '/tools/misc-python/virtualenv.py')}, 15:28:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:28:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:28:41 INFO - 'installer_path': 'installer.exe', 15:28:41 INFO - 'log_level': 'info', 15:28:41 INFO - 'log_name': 'talos', 15:28:41 INFO - 'log_to_console': True, 15:28:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:28:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:28:41 INFO - 'opt_config_files': (), 15:28:41 INFO - 'pip_index': False, 15:28:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:28:41 INFO - 'cmd': ('bash', 15:28:41 INFO - '-c', 15:28:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:28:41 INFO - 'enabled': True, 15:28:41 INFO - 'halt_on_failure': False, 15:28:41 INFO - 'name': 'check_screen_resolution'},), 15:28:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:28:41 INFO - 'cmd': ('bash', 15:28:41 INFO - '-c', 15:28:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:28:41 INFO - 'enabled': True, 15:28:41 INFO - 'halt_on_failure': False, 15:28:41 INFO - 'name': 'check_screen_resolution'},), 15:28:41 INFO - 'run_cmd_checks_enabled': True, 15:28:41 INFO - 'sps_profile': False, 15:28:41 INFO - 'sps_profile_interval': 0, 15:28:41 INFO - 'suite': 'dromaeojs-e10s', 15:28:41 INFO - 'system_bits': '32', 15:28:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:28:41 INFO - 'title': 't-yosemite-r7-0123', 15:28:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:28:41 INFO - 'use_talos_json': True, 15:28:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:28:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:28:41 INFO - 'work_dir': 'build'} 15:28:41 INFO - [mozharness: 2017-03-02 23:28:41.638287Z] Running clobber step. 15:28:41 INFO - Running pre-action listener: _resource_record_pre_action 15:28:41 INFO - Running main action method: clobber 15:28:41 INFO - rmtree: /builds/slave/test/build 15:28:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:28:44 INFO - Running post-action listener: _resource_record_post_action 15:28:44 INFO - [mozharness: 2017-03-02 23:28:44.142043Z] Finished clobber step (success) 15:28:44 INFO - [mozharness: 2017-03-02 23:28:44.142215Z] Running read-buildbot-config step. 15:28:44 INFO - Running pre-action listener: _resource_record_pre_action 15:28:44 INFO - Running main action method: read_buildbot_config 15:28:44 INFO - Using buildbot properties: 15:28:44 INFO - { 15:28:44 INFO - "project": "", 15:28:44 INFO - "product": "firefox", 15:28:44 INFO - "script_repo_revision": "production", 15:28:44 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:28:44 INFO - "repository": "", 15:28:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:28:44 INFO - "buildid": "20170302120751", 15:28:44 INFO - "pgo_build": "False", 15:28:44 INFO - "basedir": "/builds/slave/test", 15:28:44 INFO - "buildnumber": 16, 15:28:44 INFO - "slavename": "t-yosemite-r7-0123", 15:28:44 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:28:44 INFO - "platform": "yosemite_r7", 15:28:44 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:28:44 INFO - "branch": "mozilla-beta", 15:28:44 INFO - "repo_path": "releases/mozilla-beta", 15:28:44 INFO - "revision": "25ee9d2ee428d3cc16ba425ad2dd74496cf1d7a6", 15:28:44 INFO - "stage_platform": "macosx64", 15:28:44 INFO - "builduid": "4222224de113450ab8b102b935be6319", 15:28:44 INFO - "slavebuilddir": "test" 15:28:44 INFO - } 15:28:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 15:28:44 INFO - Running post-action listener: _resource_record_post_action 15:28:44 INFO - [mozharness: 2017-03-02 23:28:44.147759Z] Finished read-buildbot-config step (success) 15:28:44 INFO - [mozharness: 2017-03-02 23:28:44.147975Z] Running download-and-extract step. 15:28:44 INFO - Running pre-action listener: _resource_record_pre_action 15:28:44 INFO - Running main action method: download_and_extract 15:28:44 INFO - mkdir: /builds/slave/test/build/tests 15:28:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:28:44 INFO - trying https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 15:28:44 INFO - Downloading https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/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 15:28:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/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 15:28:45 INFO - Downloaded 1165 bytes. 15:28:45 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 15:28:45 INFO - Using the following test package requirements: 15:28:45 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 15:28:45 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:28:45 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 15:28:45 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:28:45 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 15:28:45 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:28:45 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:28:45 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 15:28:45 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 15:28:45 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:28:45 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 15:28:45 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:28:45 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 15:28:45 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:28:45 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 15:28:45 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:28:45 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 15:28:45 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 15:28:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 15:28:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 15:28:45 INFO - Fetch https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 15:28:46 INFO - Http code: 200 15:28:46 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 15:28:46 INFO - x-amz-cf-id: tVw_zQ449-t2O6HXRyOfEm9QheBjgz7CGaqfp2tXIWc45NNNh1d_cg== 15:28:46 INFO - x-amz-version-id: PcCPnrw3Oopxzyep0Um9iVT0ZaS28Z20 15:28:47 INFO - Expected file size: 16093387 15:28:47 INFO - Obtained file size: 16093387 15:28:53 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 15:28:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 15:28:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 15:28:53 INFO - Fetch https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 15:28:54 INFO - Http code: 200 15:28:54 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 15:28:54 INFO - x-amz-cf-id: 9CJ3-3QX2rrsbO3qngOM72Yuj6L7vmhvjvjrWO38IcEhIk0S_qbbdw== 15:28:54 INFO - x-amz-version-id: PcCPnrw3Oopxzyep0Um9iVT0ZaS28Z20 15:28:54 INFO - Expected file size: 16093387 15:28:54 INFO - Obtained file size: 16093387 15:29:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 15:29:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 15:29:00 INFO - Fetch https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 15:29:01 INFO - Http code: 200 15:29:01 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 15:29:01 INFO - x-amz-cf-id: Y3qQAKVMMoLChZtgKN8_JlFqlmhNhD06pVMevI6qmC6aKC1iApbFJw== 15:29:01 INFO - x-amz-version-id: .MROI4DNWBXrw8r5aOhbIOay1nIHKZ9p 15:29:01 INFO - Expected file size: 14402992 15:29:01 INFO - Obtained file size: 14402992 15:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:02 INFO - trying https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:29:02 INFO - Downloading https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 15:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/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 15:29:04 INFO - Downloaded 82116854 bytes. 15:29:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:29:04 INFO - mkdir: /builds/slave/test/properties 15:29:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:04 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:04 INFO - Contents: 15:29:04 INFO - build_url:https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:29:05 INFO - Running post-action listener: _resource_record_post_action 15:29:05 INFO - Running post-action listener: set_extra_try_arguments 15:29:05 INFO - [mozharness: 2017-03-02 23:29:05.478820Z] Finished download-and-extract step (success) 15:29:05 INFO - [mozharness: 2017-03-02 23:29:05.478937Z] Running populate-webroot step. 15:29:05 INFO - Running pre-action listener: _resource_record_pre_action 15:29:05 INFO - Running main action method: populate_webroot 15:29:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:05 INFO - '--tppagecycles', 15:29:05 INFO - '7'], 15:29:05 INFO - 'tests': ['tsvgm']}}, 15:29:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['damp', 'tps']}, 15:29:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'tests': ['damp', 'tps']}, 15:29:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['dromaeo_dom']}, 15:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['basic_compositor_video']}, 15:29:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:29:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['a11yr', 15:29:05 INFO - 'ts_paint', 15:29:05 INFO - 'tpaint', 15:29:05 INFO - 'sessionrestore', 15:29:05 INFO - 'sessionrestore_no_auto_restore', 15:29:05 INFO - 'tabpaint']}, 15:29:05 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:05 INFO - 'ts_paint', 15:29:05 INFO - 'tpaint', 15:29:05 INFO - 'sessionrestore', 15:29:05 INFO - 'sessionrestore_no_auto_restore', 15:29:05 INFO - 'tabpaint']}, 15:29:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:05 INFO - 'ts_paint', 15:29:05 INFO - 'tpaint', 15:29:05 INFO - 'sessionrestore', 15:29:05 INFO - 'sessionrestore_no_auto_restore', 15:29:05 INFO - 'tabpaint']}, 15:29:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:05 INFO - 'ts_paint', 15:29:05 INFO - 'tpaint', 15:29:05 INFO - 'sessionrestore', 15:29:05 INFO - 'sessionrestore_no_auto_restore', 15:29:05 INFO - 'tabpaint']}, 15:29:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['a11yr', 15:29:05 INFO - 'ts_paint', 15:29:05 INFO - 'tpaint', 15:29:05 INFO - 'sessionrestore', 15:29:05 INFO - 'sessionrestore_no_auto_restore', 15:29:05 INFO - 'tabpaint']}, 15:29:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['a11yr', 15:29:05 INFO - 'ts_paint', 15:29:05 INFO - 'tpaint', 15:29:05 INFO - 'sessionrestore', 15:29:05 INFO - 'sessionrestore_no_auto_restore', 15:29:05 INFO - 'tabpaint']}, 15:29:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['tsvgx', 15:29:05 INFO - 'tsvgr_opacity', 15:29:05 INFO - 'tart', 15:29:05 INFO - 'tscrollx', 15:29:05 INFO - 'cart']}, 15:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:05 INFO - 'tsvgr_opacity', 15:29:05 INFO - 'tart', 15:29:05 INFO - 'tscrollx', 15:29:05 INFO - 'cart']}, 15:29:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'talos_options': ['--disable-e10s'], 15:29:05 INFO - 'tests': ['tp5o']}, 15:29:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'tests': ['tp5o']}, 15:29:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'talos_options': ['--disable-e10s', 15:29:05 INFO - '--xperf_path', 15:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:05 INFO - 'tests': ['tp5n']}, 15:29:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:05 INFO - 'talos_options': ['--xperf_path', 15:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:05 INFO - 'tests': ['tp5n']}}, 15:29:05 INFO - 'talos.zip': {'path': '', 15:29:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:05 INFO - Running post-action listener: _resource_record_post_action 15:29:05 INFO - [mozharness: 2017-03-02 23:29:05.497804Z] Finished populate-webroot step (success) 15:29:05 INFO - [mozharness: 2017-03-02 23:29:05.497936Z] Running create-virtualenv step. 15:29:05 INFO - Running pre-action listener: _resource_record_pre_action 15:29:05 INFO - Running main action method: create_virtualenv 15:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:05 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 15:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:05 INFO - Using real prefix '/tools/python27' 15:29:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:06 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:09 INFO - Installing pip.................done. 15:29:09 INFO - Return code: 0 15:29:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:29:09 INFO - Reading from file tmpfile_stdout 15:29:09 INFO - Output received: 15:29:09 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) 15:29:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:09 INFO - 'HOME': '/Users/cltbld', 15:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:09 INFO - 'LOGNAME': 'cltbld', 15:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:09 INFO - 'MOZ_NO_REMOTE': '1', 15:29:09 INFO - 'NO_EM_RESTART': '1', 15:29:09 INFO - 'PAGER': '/bin/cat', 15:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:09 INFO - 'PWD': '/builds/slave/test', 15:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:09 INFO - 'SHELL': '/bin/bash', 15:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:09 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:09 INFO - 'USER': 'cltbld', 15:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:09 INFO - 'XPC_FLAGS': '0x0', 15:29:09 INFO - 'XPC_SERVICE_NAME': '0', 15:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:09 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 15:29:09 INFO - Cleaning up... 15:29:09 INFO - Return code: 0 15:29:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:29:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:09 INFO - 'HOME': '/Users/cltbld', 15:29:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:09 INFO - 'LOGNAME': 'cltbld', 15:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:09 INFO - 'MOZ_NO_REMOTE': '1', 15:29:09 INFO - 'NO_EM_RESTART': '1', 15:29:09 INFO - 'PAGER': '/bin/cat', 15:29:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:09 INFO - 'PWD': '/builds/slave/test', 15:29:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:09 INFO - 'SHELL': '/bin/bash', 15:29:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:09 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:09 INFO - 'USER': 'cltbld', 15:29:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:09 INFO - 'XPC_FLAGS': '0x0', 15:29:09 INFO - 'XPC_SERVICE_NAME': '0', 15:29:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:10 INFO - Downloading/unpacking psutil>=3.1.1 15:29:10 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 15:29:10 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 15:29:10 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 15:29:10 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 15:29:10 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 15:29:10 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 15:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:13 INFO - Installing collected packages: psutil 15:29:13 INFO - Running setup.py install for psutil 15:29:13 INFO - building 'psutil._psutil_osx' extension 15:29:13 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 15:29:14 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 15:29:14 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 15:29:14 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 15:29:14 INFO - building 'psutil._psutil_posix' extension 15:29:14 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 15:29:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:29:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:29:14 INFO - ^ 15:29:14 INFO - 1 warning generated. 15:29:14 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 15:29:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:14 INFO - Successfully installed psutil 15:29:14 INFO - Cleaning up... 15:29:14 INFO - Return code: 0 15:29:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:14 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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:14 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 15:29:14 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 15:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:14 INFO - 'HOME': '/Users/cltbld', 15:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:14 INFO - 'LOGNAME': 'cltbld', 15:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:14 INFO - 'MOZ_NO_REMOTE': '1', 15:29:14 INFO - 'NO_EM_RESTART': '1', 15:29:14 INFO - 'PAGER': '/bin/cat', 15:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:14 INFO - 'PWD': '/builds/slave/test', 15:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:14 INFO - 'SHELL': '/bin/bash', 15:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:14 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:14 INFO - 'USER': 'cltbld', 15:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:14 INFO - 'XPC_FLAGS': '0x0', 15:29:14 INFO - 'XPC_SERVICE_NAME': '0', 15:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:29:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:29:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:29:17 INFO - Installing collected packages: mozsystemmonitor 15:29:17 INFO - Running setup.py install for mozsystemmonitor 15:29:17 INFO - Successfully installed mozsystemmonitor 15:29:17 INFO - Cleaning up... 15:29:18 INFO - Return code: 0 15:29:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:29:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:18 INFO - 'HOME': '/Users/cltbld', 15:29:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:18 INFO - 'LOGNAME': 'cltbld', 15:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:18 INFO - 'MOZ_NO_REMOTE': '1', 15:29:18 INFO - 'NO_EM_RESTART': '1', 15:29:18 INFO - 'PAGER': '/bin/cat', 15:29:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:18 INFO - 'PWD': '/builds/slave/test', 15:29:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:18 INFO - 'SHELL': '/bin/bash', 15:29:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:18 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:18 INFO - 'USER': 'cltbld', 15:29:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:18 INFO - 'XPC_FLAGS': '0x0', 15:29:18 INFO - 'XPC_SERVICE_NAME': '0', 15:29:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:18 INFO - Downloading/unpacking jsonschema==2.5.1 15:29:18 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 15:29:18 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 15:29:18 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 15:29:18 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 15:29:18 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 15:29:18 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 15:29:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:29:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:29:20 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 15:29:20 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 15:29:20 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 15:29:20 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 15:29:20 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 15:29:20 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 15:29:21 INFO - Downloading functools32-3.2.3-2.tar.gz 15:29:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:29:21 INFO - warning: no files found matching '*.txt' 15:29:21 INFO - no previously-included directories found matching 'build' 15:29:21 INFO - no previously-included directories found matching 'dist' 15:29:21 INFO - no previously-included directories found matching '.git*' 15:29:21 INFO - Installing collected packages: jsonschema, functools32 15:29:21 INFO - Running setup.py install for functools32 15:29:21 INFO - warning: no files found matching '*.txt' 15:29:21 INFO - no previously-included directories found matching 'build' 15:29:21 INFO - no previously-included directories found matching 'dist' 15:29:21 INFO - no previously-included directories found matching '.git*' 15:29:21 INFO - Successfully installed jsonschema functools32 15:29:21 INFO - Cleaning up... 15:29:21 INFO - Return code: 0 15:29:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:29:21 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')]} 15:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:21 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')]} 15:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:21 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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:21 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 15:29:21 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 15:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:21 INFO - 'HOME': '/Users/cltbld', 15:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:21 INFO - 'LOGNAME': 'cltbld', 15:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:21 INFO - 'MOZ_NO_REMOTE': '1', 15:29:21 INFO - 'NO_EM_RESTART': '1', 15:29:21 INFO - 'PAGER': '/bin/cat', 15:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:21 INFO - 'PWD': '/builds/slave/test', 15:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:21 INFO - 'SHELL': '/bin/bash', 15:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:21 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:21 INFO - 'USER': 'cltbld', 15:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:21 INFO - 'XPC_FLAGS': '0x0', 15:29:21 INFO - 'XPC_SERVICE_NAME': '0', 15:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:29:21 INFO - Cleaning up... 15:29:21 INFO - Return code: 0 15:29:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:29:21 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')]} 15:29:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:21 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')]} 15:29:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:21 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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:21 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 15:29:21 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 15:29:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:21 INFO - 'HOME': '/Users/cltbld', 15:29:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:21 INFO - 'LOGNAME': 'cltbld', 15:29:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:21 INFO - 'MOZ_NO_REMOTE': '1', 15:29:21 INFO - 'NO_EM_RESTART': '1', 15:29:21 INFO - 'PAGER': '/bin/cat', 15:29:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:21 INFO - 'PWD': '/builds/slave/test', 15:29:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:21 INFO - 'SHELL': '/bin/bash', 15:29:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:21 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:21 INFO - 'USER': 'cltbld', 15:29:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:21 INFO - 'XPC_FLAGS': '0x0', 15:29:21 INFO - 'XPC_SERVICE_NAME': '0', 15:29:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:22 INFO - Downloading/unpacking blobuploader==1.2.4 15:29:22 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 15:29:22 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 15:29:22 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 15:29:22 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 15:29:22 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 15:29:22 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 15:29:24 INFO - Downloading blobuploader-1.2.4.tar.gz 15:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:29:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:29:24 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 15:29:24 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 15:29:24 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 15:29:24 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 15:29:24 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 15:29:24 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 15:29:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:29:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:29:25 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 15:29:25 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 15:29:25 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 15:29:25 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 15:29:25 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 15:29:25 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 15:29:26 INFO - Downloading docopt-0.6.1.tar.gz 15:29:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:29:26 INFO - Installing collected packages: blobuploader, requests, docopt 15:29:26 INFO - Running setup.py install for blobuploader 15:29:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:29:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:29:26 INFO - Running setup.py install for requests 15:29:26 INFO - Running setup.py install for docopt 15:29:26 INFO - Successfully installed blobuploader requests docopt 15:29:26 INFO - Cleaning up... 15:29:27 INFO - Return code: 0 15:29:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:27 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')]} 15:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:27 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')]} 15:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:27 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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:27 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 15:29:27 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 15:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:27 INFO - 'HOME': '/Users/cltbld', 15:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:27 INFO - 'LOGNAME': 'cltbld', 15:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:27 INFO - 'MOZ_NO_REMOTE': '1', 15:29:27 INFO - 'NO_EM_RESTART': '1', 15:29:27 INFO - 'PAGER': '/bin/cat', 15:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:27 INFO - 'PWD': '/builds/slave/test', 15:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:27 INFO - 'SHELL': '/bin/bash', 15:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:27 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:27 INFO - 'USER': 'cltbld', 15:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:27 INFO - 'XPC_FLAGS': '0x0', 15:29:27 INFO - 'XPC_SERVICE_NAME': '0', 15:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-DWJQV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-yn9EXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-qsJAOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ILrNaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-OHH11s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-AZbKo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-xSO6LT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:27 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-tMJREW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-FWQXQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-oUvwMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-BSy5iP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6fVSQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-pP5ZeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-UWDlOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Tw0fhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-myEF2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:28 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-snVLjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:29:29 INFO - Running setup.py install for manifestparser 15:29:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:29:29 INFO - Running setup.py install for mozcrash 15:29:29 INFO - Running setup.py install for mozdebug 15:29:29 INFO - Running setup.py install for mozdevice 15:29:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:29:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:29:29 INFO - Running setup.py install for mozfile 15:29:29 INFO - Running setup.py install for mozhttpd 15:29:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:29:29 INFO - Running setup.py install for mozinfo 15:29:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Running setup.py install for mozInstall 15:29:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Running setup.py install for mozleak 15:29:30 INFO - Running setup.py install for mozlog 15:29:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Running setup.py install for moznetwork 15:29:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Running setup.py install for mozprocess 15:29:30 INFO - Running setup.py install for mozprofile 15:29:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:29:30 INFO - Running setup.py install for mozrunner 15:29:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:29:31 INFO - Running setup.py install for mozscreenshot 15:29:31 INFO - Running setup.py install for moztest 15:29:31 INFO - Running setup.py install for mozversion 15:29:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:29:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:29:31 INFO - Cleaning up... 15:29:31 INFO - Return code: 0 15:29:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:31 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')]} 15:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:31 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')]} 15:29:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:31 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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:31 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 15:29:31 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 15:29:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:31 INFO - 'HOME': '/Users/cltbld', 15:29:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:31 INFO - 'LOGNAME': 'cltbld', 15:29:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:31 INFO - 'MOZ_NO_REMOTE': '1', 15:29:31 INFO - 'NO_EM_RESTART': '1', 15:29:31 INFO - 'PAGER': '/bin/cat', 15:29:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:31 INFO - 'PWD': '/builds/slave/test', 15:29:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:31 INFO - 'SHELL': '/bin/bash', 15:29:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:31 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:31 INFO - 'USER': 'cltbld', 15:29:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:31 INFO - 'XPC_FLAGS': '0x0', 15:29:31 INFO - 'XPC_SERVICE_NAME': '0', 15:29:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:29:31 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-GcDDNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:29:31 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)) 15:29:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:29:31 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-IC9Jc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-eow9H7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-67rskU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-UgpTVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-GUFeoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-QIKfrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-56MPki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-SJnXbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ITc8lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:29:32 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)) 15:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:29:32 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-8AMeQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:29:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-7cIHrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:29:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-MiUNj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:29:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-yLrWgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-peZb7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:29:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-twYtAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:29:33 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)) 15:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:29:33 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-CoW7ez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:29:33 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)) 15:29:33 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)) 15:29:33 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)) 15:29:33 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)) 15:29:33 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)) 15:29:33 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)) 15:29:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:29:33 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 15:29:33 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 15:29:33 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 15:29:33 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 15:29:33 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 15:29:33 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 15:29:36 INFO - Downloading blessings-1.6.tar.gz 15:29:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:29:36 INFO - Installing collected packages: blessings 15:29:36 INFO - Running setup.py install for blessings 15:29:36 INFO - Successfully installed blessings 15:29:36 INFO - Cleaning up... 15:29:36 INFO - Return code: 0 15:29:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:29:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:36 INFO - Reading from file tmpfile_stdout 15:29:36 INFO - Current package versions: 15:29:36 INFO - blessings == 1.6 15:29:36 INFO - blobuploader == 1.2.4 15:29:36 INFO - docopt == 0.6.1 15:29:36 INFO - functools32 == 3.2.3-2 15:29:36 INFO - jsonschema == 2.5.1 15:29:36 INFO - manifestparser == 1.1 15:29:36 INFO - mozInstall == 1.12 15:29:36 INFO - mozcrash == 1.0 15:29:36 INFO - mozdebug == 0.1 15:29:36 INFO - mozdevice == 0.48 15:29:36 INFO - mozfile == 1.2 15:29:36 INFO - mozhttpd == 0.7 15:29:36 INFO - mozinfo == 0.9 15:29:36 INFO - mozleak == 0.1 15:29:36 INFO - mozlog == 3.4 15:29:36 INFO - moznetwork == 0.27 15:29:36 INFO - mozprocess == 0.23 15:29:36 INFO - mozprofile == 0.28 15:29:36 INFO - mozrunner == 6.13 15:29:36 INFO - mozscreenshot == 0.1 15:29:36 INFO - mozsystemmonitor == 0.3 15:29:36 INFO - moztest == 0.8 15:29:36 INFO - mozversion == 1.4 15:29:36 INFO - psutil == 3.1.1 15:29:36 INFO - requests == 1.2.3 15:29:36 INFO - wsgiref == 0.1.2 15:29:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:37 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 15:29:37 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 15:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:37 INFO - 'HOME': '/Users/cltbld', 15:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:37 INFO - 'LOGNAME': 'cltbld', 15:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:37 INFO - 'MOZ_NO_REMOTE': '1', 15:29:37 INFO - 'NO_EM_RESTART': '1', 15:29:37 INFO - 'PAGER': '/bin/cat', 15:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:37 INFO - 'PWD': '/builds/slave/test', 15:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:37 INFO - 'SHELL': '/bin/bash', 15:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:37 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:37 INFO - 'USER': 'cltbld', 15:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:37 INFO - 'XPC_FLAGS': '0x0', 15:29:37 INFO - 'XPC_SERVICE_NAME': '0', 15:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 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)) 15:29:37 INFO - Cleaning up... 15:29:37 INFO - Return code: 0 15:29:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:37 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 0x10aaca490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa40570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92ea465100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, '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.aA8z4bia0r/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.tTVuqSiSsI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 15:29:37 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 15:29:37 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 15:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:29:37 INFO - 'HOME': '/Users/cltbld', 15:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:29:37 INFO - 'LOGNAME': 'cltbld', 15:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:37 INFO - 'MOZ_NO_REMOTE': '1', 15:29:37 INFO - 'NO_EM_RESTART': '1', 15:29:37 INFO - 'PAGER': '/bin/cat', 15:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:37 INFO - 'PWD': '/builds/slave/test', 15:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:29:37 INFO - 'SHELL': '/bin/bash', 15:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:29:37 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:29:37 INFO - 'USER': 'cltbld', 15:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:37 INFO - 'XPC_FLAGS': '0x0', 15:29:37 INFO - 'XPC_SERVICE_NAME': '0', 15:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:29:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:29:38 INFO - Cleaning up... 15:29:38 INFO - Return code: 0 15:29:38 INFO - Running post-action listener: _resource_record_post_action 15:29:38 INFO - Running post-action listener: _start_resource_monitoring 15:29:38 INFO - Starting resource monitoring. 15:29:38 INFO - [mozharness: 2017-03-02 23:29:38.262380Z] Finished create-virtualenv step (success) 15:29:38 INFO - [mozharness: 2017-03-02 23:29:38.263349Z] Running install step. 15:29:38 INFO - Running pre-action listener: _resource_record_pre_action 15:29:38 INFO - Running main action method: install 15:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:29:38 INFO - Reading from file tmpfile_stdout 15:29:38 INFO - Detecting whether we're running mozinstall >=1.0... 15:29:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:29:38 INFO - Reading from file tmpfile_stdout 15:29:38 INFO - Output received: 15:29:38 INFO - Usage: mozinstall [options] installer 15:29:38 INFO - Options: 15:29:38 INFO - -h, --help show this help message and exit 15:29:38 INFO - -d DEST, --destination=DEST 15:29:38 INFO - Directory to install application into. [default: 15:29:38 INFO - "/builds/slave/test"] 15:29:38 INFO - --app=APP Application being installed. [default: firefox] 15:29:38 INFO - mkdir: /builds/slave/test/build/application 15:29:38 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'] 15:29:38 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 15:29:59 INFO - Reading from file tmpfile_stdout 15:29:59 INFO - Output received: 15:29:59 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:29:59 INFO - Running post-action listener: _resource_record_post_action 15:29:59 INFO - [mozharness: 2017-03-02 23:29:59.789088Z] Finished install step (success) 15:29:59 INFO - [mozharness: 2017-03-02 23:29:59.789243Z] Running run-tests step. 15:29:59 INFO - Running pre-action listener: _resource_record_pre_action 15:29:59 INFO - Running main action method: run_tests 15:29:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:29:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:29:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:29:59 INFO - 2017-03-02 15:29:59.803 screenresolution[1620:7358] starting screenresolution argv=screenresolution get 15:29:59 INFO - 2017-03-02 15:29:59.806 screenresolution[1620:7358] Display 0: 1600x1200x32@60 15:29:59 INFO - 2017-03-02 15:29:59.812 screenresolution[1621:7361] starting screenresolution argv=screenresolution list 15:29:59 INFO - Available Modes on Display 0 15:29:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:29:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:29:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:29:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:30:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:30:00 INFO - Intel Iris: 15:30:00 INFO - Chipset Model: Intel Iris 15:30:00 INFO - Type: GPU 15:30:00 INFO - Bus: Built-In 15:30:00 INFO - VRAM (Dynamic, Max): 1536 MB 15:30:00 INFO - Vendor: Intel (0x8086) 15:30:00 INFO - Device ID: 0x0a2e 15:30:00 INFO - Revision ID: 0x0009 15:30:00 INFO - Displays: 15:30:00 INFO - Display: 15:30:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:30:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:30:00 INFO - Display Serial Number: 1600x1200 60 15:30:00 INFO - Main Display: Yes 15:30:00 INFO - Mirror: Off 15:30:00 INFO - Online: Yes 15:30:00 INFO - Rotation: Supported 15:30:00 INFO - Return code: 0 15:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:30:00 INFO - Python 2.7.3 15:30:00 INFO - Return code: 0 15:30:00 INFO - grabbing minidump binary from tooltool 15:30: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')]} 15:30:00 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 0x10a8ded30>, '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 0x10a744db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8d6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:30:00 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 15:30:00 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 15:30:00 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 15:30:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:30:00 INFO - Return code: 0 15:30:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:30:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:30:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:30:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:30:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:30:00 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/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 15:30:00 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/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 15:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aA8z4bia0r/Render', 15:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:30:00 INFO - 'HOME': '/Users/cltbld', 15:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:30:00 INFO - 'LOGNAME': 'cltbld', 15:30:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:00 INFO - 'MOZ_NO_REMOTE': '1', 15:30:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:00 INFO - 'NO_EM_RESTART': '1', 15:30:00 INFO - 'PAGER': '/bin/cat', 15:30:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:00 INFO - 'PWD': '/builds/slave/test', 15:30:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:30:00 INFO - 'SHELL': '/bin/bash', 15:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tTVuqSiSsI/Listeners', 15:30:00 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 15:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:30:00 INFO - 'USER': 'cltbld', 15:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:00 INFO - 'XPC_FLAGS': '0x0', 15:30:00 INFO - 'XPC_SERVICE_NAME': '0', 15:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:30:00 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/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 15:30:00 INFO - mozversion application_buildid: 20170302120751 15:30:00 INFO - mozversion application_changeset: 25ee9d2ee428d3cc16ba425ad2dd74496cf1d7a6 15:30:00 INFO - mozversion application_display_name: Firefox 15:30:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:30:00 INFO - mozversion application_name: Firefox 15:30:00 INFO - mozversion application_remotingname: firefox 15:30:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:30:00 INFO - mozversion application_vendor: Mozilla 15:30:00 INFO - mozversion application_version: 52.0 15:30:00 INFO - mozversion platform_buildid: 20170302120751 15:30:00 INFO - mozversion platform_changeset: 25ee9d2ee428d3cc16ba425ad2dd74496cf1d7a6 15:30:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:30:00 INFO - mozversion platform_version: 52.0 15:30:00 INFO - using testdate: 1488497400 15:30:00 INFO - actual date: 1488497400 15:30:00 INFO - starting webserver on 'localhost:49235' 15:30:00 INFO - SUITE-START | Running 2 tests 15:30:00 INFO - TEST-START | dromaeo_css 15:30:00 INFO - operating with platform_type : mac_ 15:30:00 INFO - Initialising browser for dromaeo_css test... 15:30:00 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpjyQIPu/profile http://localhost:49235/getInfo.html) 15:30:03 INFO - PROCESS | 1632 | 2017-03-02 15:30:03.347 plugin-container[1635:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 15:30:03 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:03 INFO - PROCESS | 1632 | 2017-03-02 15:30:03.348 plugin-container[1635:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x921f, name = 'com.apple.CFPasteboardClient' 15:30:03 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:03 INFO - PROCESS | 1632 | 2017-03-02 15:30:03.348 plugin-container[1635:7547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:03 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 15:30:03 INFO - PROCESS | 1632 | colorDepth:24 15:30:03 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 15:30:03 INFO - PROCESS | 1632 | __metrics 15:30:03 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 15:30:04 INFO - TEST-INFO | 1632: exit 0 15:30:04 INFO - Browser initialized. 15:30:04 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:04 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpjyQIPu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:06 INFO - PROCESS | 1636 | 2017-03-02 15:30:06.642 plugin-container[1637:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 15:30:06 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:06 INFO - PROCESS | 1636 | 2017-03-02 15:30:06.643 plugin-container[1637:7672] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.CFPasteboardClient' 15:30:06 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:30:06 INFO - PROCESS | 1636 | 2017-03-02 15:30:06.643 plugin-container[1637:7672] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:30:16 INFO - PROCESS | 1636 | RSS: Main: 177057792 15:30:16 INFO - PROCESS | 1636 | 15:33:18 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 15:33:20 INFO - PROCESS | 1636 | RSS: Main: 182710272 15:33:20 INFO - PROCESS | 1636 | 15:36:22 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 15:36:23 INFO - PROCESS | 1636 | RSS: Main: 191713280 15:36:23 INFO - PROCESS | 1636 | 15:39:25 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 15:39:25 INFO - PROCESS | 1636 | RSS: Main: 198545408 15:39:25 INFO - PROCESS | 1636 | 15:42:27 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 15:42:28 INFO - PROCESS | 1636 | RSS: Main: 201687040 15:42:28 INFO - PROCESS | 1636 | 15:45:30 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 15:45:31 INFO - PROCESS | 1636 | RSS: Main: 203038720 15:45:31 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 15:48:34 INFO - PROCESS | 1636 | RSS: Main: 203153408 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | __start_tp_report 15:48:34 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 15:48:34 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 15:48:34 INFO - PROCESS | 1636 | |i|pagename|runs| 15:48:34 INFO - PROCESS | 1636 | |0;dojo.html;3559.3415218184637;3582.6238244984274;3588.4976103345552;3594.3889538778403;3632.110133017411;5967.880642387155;6031.4270144336215;6043.6373817570975;6055.485283750881;6071.150038994533;5838.795642152526;5940.673262970535;6128.540359473035;6135.846603834906;6159.445649891509;4511.548845115495;4511.706739061967;5450.427705090968;5465.845927238166;5516.862078448051;5478.205660179272;5500.092484740033;5514.80698175564;5520.503154716075;5523.226748255248;5318.654287471321;5345.198220266952;5493.148561972901;5497.505224529811;5525.585581081415;4536.410266665345;4567.840125595588;4981;5037.899242015176;5502.862428439281;4551.271796512575;5367.892642147174;5471.863203419906;5477.616566840323;5500.009998200322;5884.705764711747;6089.908651370233;6126.846828829315;6138.815835524942;6152.292486364081;5457.727113644302;6241.3446588108045;6244.31312555623;6271.404216599416;6299.9370006299805;6376.33048529907;6387.552871299011;6421.454176395012;6484.773032943872;6490.156279683658;6105.542084343692;6133.938660613426;6139.96930015356;6159.568830181934;6164.075388691687;6541.934580654228;6549.181352330958;6628.801135965929;6634.469242460592;6660.467162627075;6298.968505157445;6322.430981211713;6323.430891219812;6327.082573026885;6341.682915854189;6176.474999625082;6212.937870621236;6455.4512866406885;6463.224413070461;6485.027245913151;6209.96895015522;6257.812265631947;6273.1844860167885;6331.493480521639;6336.049592561153;6293.874122517615;6317.147185129948;6331.905021424683;6335;6357.11000459936;5072.365954255718;5179.585633149339;5180.197069454241;5189.455107213764;5234.738263086831;4566.383538222364;4577.908441831145;4582.587567118975;4597.586217240464;4653.488116307203;5953.970230148821;6130;6160.784372546939;6168.907466388007;6205.503559715212;5188.351456067991;6433.195850518685;6435.06691529735;6468.223813142453;6487.643179625166;6010.879782404328;6026.9095963560585;6092.939070609237;6122.398155112068;6142.078688196717;5680.0627896397455;5688.544916406678;5689.260396148474;5709.74306156224;5719.970405326998;6671.833204169838;6699.899501507482;6714.462842972648;6730.057791909038;6762.560433571802;6210.223722034746;6235.064740288993;6314.052892066226;6330.715117819801;6466.805995820133;6714.597124172566;6718.899216511659;6723.899141512783;6728.798136055926;6732.966335168293;6363.936360636334;6367.42693157618;6369.7452101915405;6378.202724659418;6380.585261957958;6143.201383820075;6274.403931626488;6314.463270622048;6389.137466442156;6431.5819471734185;6141.301904043499;6270.968645156928;6294.181756371642;6305.5270854687;6312.116303717575;6249.500039996698;6254.718537665725;6262.937370626236;6281;6323.7786677466065;5409.188621706776;5913.201717768218;5997.970010150096;6197.4732147767945;6247.68761561911;6201.379862013762;6219.813405597839;6249.812505624838;6279.309275979547;6466.159399278064;6164.938350616437;6191.442770150528;6196.504279657706;6205.596636218631;6226.62640241587 15:48:34 INFO - PROCESS | 1636 | |1;ext.html;501.84944516645;531.4260598553562;540.370468404309;544.4229117135835;566.2355819643482;37793.81103094484;40036.79981600095;40138;40204.59795402045;40233.19533609326;41423.58576414235;41693.166136677246;41974.58025419745;42087;42233.155336893244;9536.809263814719;9688.66089686861;9710.70867873965;9719.368241064343;9726.17327527161;9678.17735492484;9730.805383892319;9761.804763904718;9764.560594773253;9802;9667.5649595768;9708.902910970906;9724.56239469222;9725.27060470464;9740;9643.80712385752;9708.368956017906;9710.417374957526;9720.416774993522;9736.561854716554;9602.66390676323;9611.038896110333;9649.469279189641;9699.563519641633;9756.414615123116;39189;39756.801215994026;39949.00127496807;40030;40282.19435611301;2752.573351130558;3316.1046517440395;3338.3824291465594;3381.4758712399616;3418.6581341865863;29307.706922930924;30081.699183008328;30100.946466873775;30427.087187384415;30729.692703072906;3136.8431578421214;3170.1282147409415;3321.368939901411;3357.362101200764;3361.5293858859777;489298;490067;490205;491331;491857.99999999645;50945.74527127341;61772.69113654493;62220.75558488895;62346.37653623406;62483.687581561804;63751.36248637454;64139;64349.67825160844;64484.677576611815;64662.676686616265;15751.212439377985;15952.840471595367;15981.28084236212;15990.200489975454;16003.199840008187;12187.268763874195;12206.755864882653;12258;12263;12323.75352492945;15374.461893833886;17132.057736824594;17667.38164164248;17848;17979.01115438637;5508.256385387921;5825.0679891217205;6279.372062793684;6337.33457986914;6411.647359395185;876.6274333408276;958.8753462049889;999.9950000249952;1031.4224034540682;1054.9208809339177;30351;30412.847935760623;30455.69544304573;31015;31150.844245778626;865.6796985115548;931.9487428191379;956.9856452153223;1065.64300959178;1079.6976846482996;12472.625821225378;13325.600231992861;15089;15343.53969380898;16533.83466165347;448923;593386;596861;597950;598126;58316.708416457644;58595.414045859;58840.11739823906;58944.705276474204;59107;1334.179479620046;1357.7148798752175;1399.6850708590487;1405.1779708870222;1410.4062409477226;30250.394992100475;31391.529127063568;31405.68594314119;31439.371212575163;31879;51572.48427515677;51996.74001629968;52155.47844521506;52624.473755261955;52711.47288527219;587614;588244;588501.057494727;589903;594163;24071.037158513464;25137.49725005526;26343.736562634127;26446.239974588996;27055;46348.609541713806;46535.53464465447;47352.52647473481;47749;48090.03819923652;36792;37960.05099872525;38256.61743382642;39639.2072158561;39665.60334396735;33363.999080027636;36937;38989.610103898594;39755.60244397519;40212.3968140484;37530.24939501249;38005.42991855069;38427.35963376908;38989.610103898594;39014.6098539011;27896;29494.852525737235;31860.681393185772;34450.82774586111;34462.310753785285;21567.784322157204;25571.48857022812;25879.353016174748;26275.73724262733;27182.592261116493 15:48:34 INFO - PROCESS | 1636 | |2;jquery.html;378.10200773163734;378.40577261867406;386.75054589789585;393.90743175353794;414.0187755020602;18877.339293124725;19782.703259451082;20361.28735494256;20438.489037774063;20445.875476848767;15571.766423503628;18838.152283147258;18902.81097189031;19064.2374305028;19439.805601943975;5826.475617194452;5842.503387212092;5969.850753731148;6045.395460453941;6086.026235802273;5886.323072846634;5901.203337549418;5962.8807423851495;6005.099235114724;6016;5962.552808539355;7269.818254543626;7623.9237607624045;7658.310752032316;7658.923410765877;6479;7326.120865496148;7572.7349542766015;7642.694292228304;7766.805829854242;7214.458915581353;7447.031885854858;7479.663415146331;7480.812979675497;7500.137484189334;28050.43899122006;28512.572311415344;28543.85728071367;28776.568351474743;29129.271768205752;2214.0221402214015;2298.5058212484396;2394.832361734679;2490.7384724603835;2648.602709593557;16377;16582.336706531725;16907.91546042262;16941.83058169415;17733.645327093516;1927.258005667828;2006.48834547191;2023.5244717491378;2041.836653067751;2065.4320061982926;118779.40610296893;122021.38989304997;123241.9999999991;125709;129527;22102.447438813837;22400.88799555992;22632.321030369116;22775.202867899545;22894.885525572266;29691.703082969325;36221.637783622355;36566.902992910254;37119;37146.32841522138;8777.341699372444;10520.210984176218;10685.465726713634;10880.619178328721;10962.342259464458;8725.607347669371;8730.519821409884;8747.475151490931;8898.777530561658;8939.463632182089;8128.309093726981;8254;10144.087032167141;10154.949225253975;10251.58993640261;3921.5490218624655;3940.2513522430645;3941.5270167580074;3962.583928687446;3990.7206495545033;847.8257613205632;855.5337341149088;867.7700409391516;902.1155104387605;988.0662773678948;17570.82429175692;20494.590108197754;21049.89475052615;21088.472788180417;21160.788392115883;957.4351132831663;957.7844984878346;959.2374062620233;960.6109525642127;990.9603615855285;10178.541965611565;10606.840897386546;10623.893761062445;10682.305650132717;10689.037986581245;616673;626771;633945;647888;648088;143931;144279;144508;144928.27535862464;145991;926.36081104038;930.9767255818658;951.2627713522076;951.357833462424;974.7952929884661;17704.114794260495;18538.07309634486;18572.628547428707;18667.34664286798;18694.34569790051;43369;58693.70653146707;59173.70413147907;59651.701741491015;60437;82527;82545.349093019;83303.5834820822;83314;86113.56943215244;8137;8396.832063358821;8414.663413463637;8466.872996905175;8478;11922.463489143183;12114.575989840314;12844.935775321064;13183.868161318263;13687.11033782801;11378.08975281992;11422.48598813072;11535.96176344133;11573.247738896946;11859.822102668639;11153.888461115284;11222.55109795599;11314.830277546009;11527.654170374903;11635.41822908868;8882.467051976902;10042.99570043019;11338.380572440667;11727.413629318671;11766.352850593299;8387.664493420194;8424.073351931405;8450.239478446969;8559.486430814171;8686.261667758312;10154.796904062025;10189;10434.747830260418;11323.716907077389;11521.88478115208 15:48:34 INFO - PROCESS | 1636 | |3;mootools.html;1505.6386467247858;1532.5555588879226;1617.223732608348;1713.2975480053174;1850.2229063793206;7640.35057020154;7899.881501777478;8131.064927533335;8573.957130214356;8574;7556.5466072035715;8052.19478052196;8359.874601880976;8415.074341822394;8437.409381343308;2250.437390652337;2253.0987604957986;2266.433391652087;2275.24916777463;2288.8168946484325;2131.6589345704692;2359.893804778789;2411.3850968003135;2434.47658753368;2449.3509220056612;2327.976720232801;2362.3857796972825;2374.0622454130607;2383.3207535852284;2409.2772168349425;2358.3750306168877;2367.3844800351944;2393.389685630171;2393.904243830262;2394.521095780842;2383.0944241188413;2397.7722116398913;2402.2793162051316;2407.4944261705064;2417.2506522977933;8299.460535065202;8469.70356037542;8470.195331443503;8475.788105297417;8508.702195423193;5081.3140226069745;5199.324087868591;5203.271541984125;5216.347956505437;5228.137357336035;3541.645835416438;3552.7513074084823;3562.8040457774814;3633.0372451300427;3669.0643885809213;1064.9520771565221;1084.7993121272589;1114.1977776001265;1126.2622981946838;1136.6817291158488;9298.488583127924;10828.891711082946;10895.673129806119;10915.890841091566;10948.507317170746;4495.955040449619;4662.184117779375;4747.928781068286;4761.7142971421135;4810.278458231223;8220.095789463154;9466.810663786686;9555.761105972406;9563.521823908777;9581.808363832684;5161.174212126042;5187.714675692873;5197.558207552401;5214.087534681492;5238.030964271622;4339.34909763538;4344.587264209895;4729.125111854318;4731.455882573479;4805.990741944231;3581.8209089545417;3847.7306588539095;3886.242182774332;3943.5464921533817;3999.7000224983244;1303.8135296879793;1308.1235572166672;1314.9802752958713;1324.6158614001831;1328.0106320791072;754.6453167011496;773.918738532446;891.1222445890793;896.9506677132821;906.2161230535539;5507.173923911445;5627.296587926509;5752.539796816244;5882;5888.587798854039;388.5065966222881;429.42671533503125;454.15073811971166;456.682605589119;461.49235840575096;3369.4103531882315;3483.390406678821;3622.2212224371888;3859.6526312632;3913.6673382762215;9842.311038227255;10087.789465264215;10181.847272290921;10192.592296308212;10198.745031374126;10116.645917392856;10160;10165.237607179492;10179.949100254451;10237.23220758446;7613.276738709814;7773.533587984739;7860.371170306362;7899.565523896241;7960.601969901481;5113.41195762492;5151.742412879416;5167.173252279767;5326.627136100436;5363.463653634605;9326.860097098684;9419;9452.054794520493;9454.527273636428;9487.857682134636;9280.350375473652;9335.299852510952;9444.433333999981;9604.519774011133;9652.083052110038;7056.223815380202;7105.893411598727;7182.533135346186;7216.530925489827;7249.420046396381;7692.0384951881015;7778.49439786412;7792.10390805042;7804.9609751950875;7858.5677787722225;6797.286284940208;7033.402160816388;7162.570245785061;7210.89183662235;7255.891161632685;6948.409385202314;7011.368976792113;7067.717291308496;7147.428205743424;7184.425245980414;6738.966305168443;6754.2570317266045;6765.594064356154;6790.117284752951;6824.249332573512;4443.600075993176;4477.126960242721;4509.2559727645885;4519.209138400701;4520.141173177086;4624.28323609841;4720.811167553259;4782.808687652455;4824.155772739827;4916.532929371704 15:48:34 INFO - PROCESS | 1636 | |4;prototype.html;315.6811620263534;319.56698673297683;320.1835319934168;326.505344403229;326.5053444032292;4715.787789549474;4991.376077990251;5481.643693159946;5530.806421775239;5622.606417550768;4954.033963377143;5341.839744807652;5389.245505629215;5393.651587103224;5412.2384942464905;3947.624975627318;4022.175454031931;4047.271491131595;4088.3867419886956;4177.874663760077;3383.5263063171174;4104.076582768883;4109.650679692215;4132.594917727972;4167.166326897708;4055.472788537501;4134.855280065199;4152.086540960999;4157.896052598679;4165.042040330711;3821.617838216156;4000.5599384067727;4064.959350406488;4087.550369459357;4132.2768515509815;2795.916122516328;3201.823899685516;3564.41187204111;3575.964240357589;3587.964120358789;4606;5340.54605358545;5376.354837419495;5389.416038334502;5395.408354535382;3915.4126880968083;4511.751853648081;4728.527147285278;4744.881377965544;4787.832425865078;5215.556677682402;5697.857553561153;5707.3721890592005;5712.200291959129;5796.13058041293;3550.5739311282555;3925.548561915388;3925.548561915388;3937.42907278443;3950.9012274693077;4975.875603109951;5080.923786143211;5583.916241256425;5738.454846789548;6085.908711369333;3880.0299925018744;3944.928912321054;3967.7222594418113;3974.940375894364;3982.6216509431556;3710.925781484409;3881.0491429599365;4011.4183443400543;4050.858219962287;4052.0275133967634;4062.5531191568903;4727.763611819395;4731.858044258678;4735.455422626374;4743.715377077387;4720.12677654635;4895.657303988686;5404.945950540444;5818.796342128006;5854.560907931922;1089.226649079147;1140.6235942139074;1159.7622487390065;1162;1315.9210447373189;789.3527307607707;853.2278288149234;872.9825403491894;899.1772528136681;907.1971305394774;4837.32277481153;4988.6507944444265;5057.570106540912;5110.3356563646485;5150.742462876841;5074.898502030012;5319.813806516753;5329.267987903947;5695.886082278331;5738.426157384311;4654.790534425959;4659.091477161921;4797.976010119997;4823.541763532458;5128.640995130379;2643.788496920242;2703.6890757562737;2899.2461959890156;2908.4473949949866;2951.9557206641916;5426.375966763872;5472.890542189213;5586.55307575393;5732.369439361667;6006.2191915051635;5691.630044047124;5704.632577480382;5780.5953583248765;6120.479759220516;6133.080037994337;5503.587230957694;5857.736401861926;6218.875622487615;6310.968445157745;6321.431071203614;4250.489941207075;5076.9338438928235;5316.388615309289;6013.909791353046;6087.569421186106;5410.509277299753;5742.052561327334;5867.647941123546;5940.782139661445;5976.491998180203;5566.91649625264;5645.612632420515;5825.94174058254;6270.122182894307;6320.525960553069;3356.3622911646708;3395.524626552235;3435.8282085895107;3990.920181596294;4027.3153563894793;5241.816536421207;5399.811006614749;5722;5960.433758792908;6065.72704228302;5438.864028399322;5828.94171058284;5845.707714614167;5852.414758524284;5915.911261330997;4555.658325625657;5037.874053148701;5458.399576046552;5636.943630563642;5776.653400795965;5626.465485778845;5636.436356364331;5666.914996274977;5685.431456854281;5714.342850572238;5044.873878153076;5528.143137813645;5676.375598684059;5842.444967728059;5881.735321910439;5206.713630750345;5680.932296559811;5735.369109398046;5783;5841.8454034820725 15:48:34 INFO - PROCESS | 1636 | |5;yui.html;704.2288693880199;724.5471580262335;742.9257074292568;783.6434422337837;809.9352051835851;13818.516351927685;14558;14628.634284142876;14642.34109465074;14671.559853204408;13081.018923580747;13098;13115.868841311583;13122.671933201675;13228.603141905758;691.72331067573;709.0604948443333;836.113719457374;871.877937088808;886.0120965123873;773.8916551682769;859.325429537813;871.5685735560907;873.7640836974014;875.3959767760225;921.4655499810127;929.1683942871126;929.4841363043508;931.7810314576069;932.1377725603824;874.5277550122958;879.2965627497977;916.4363916191561;921.9216366608779;922.299052719931;650.5608714117951;661.4344735251387;685.3831551603547;687.9484038697118;690.1683471416931;13810.447582096704;13920.93039534806;13981.510647127403;14024.439022439192;14561.126332420088;14228;14285.85714142866;14387.640308992255;14467.421303147861;14613.707725845423;11418.71453213668;11505.597304094317;11623;11703.356315402649;11755.35345555994;3699.519062521882;3791.6018818023986;3806.5241844769403;3844.442555829417;3849.8460061597502;70134.2986570138;89178;94694.99999999932;95435;95581.04418955791;10866.891331086747;11094.889051109547;11102.958426298776;11188.054426226247;11245.83131253032;22265.66601500979;22360.105595775985;22432.439189020406;22603.773962260166;22637.320880373616;7933.484323518953;7934.325582325567;7967.920320796834;8109.675612975415;8208.302294304931;4355.477342718894;4370.978145109254;4401.823927042947;4465.665075119379;4470.709403888737;3491.1097670093964;3600.71194304455;3602.5136606558303;3678.9448158277646;3841.0397400649836;690.9585424874423;696.8710788504143;703.5216053083952;706.1808302369227;709.1561042359575;531.9388270348959;664.1598378051768;678.4504551313353;691.68182635987;699.0597646165899;8704.173103555153;9315.34792564514;9365.953170234105;9522.523873806282;9648.793900762405;589.7257775134583;597.3697748874891;632.2444678609018;667.8731041102175;670.0518765946229;4145.274197813619;4322.984581388153;4395.450568678915;4704.129735998783;4790.401199850019;117239.41380293044;118831.40584297196;120804;120869;123910.38044809898;70131;84115.31769364701;84355.5782221085;108008;109740;77340.83988740173;80273.59863200647;82921.58539207265;83045;85939.71090433649;9385.436873787594;9905.207583393167;9932;9943.950280248553;9966.55150518243;54571.72714136404;54795;54952.299736188776;55362.44637553734;56119;74420.62789686234;77546.06134846674;79845;79921.49322281391;82232.3553528938;10726.49829023953;12318.445669945053;12357.073219508393;12396.00831933425;12687.11190216676;11682.94158529202;13737.793933091212;14462;14503.419863205776;14887.181205033827;11606.825897611374;12612.243265404106;13414.329283535979;13522.323883805573;14080.36638351256;13505.459781608626;13632.795508067189;13643;13795.724085518434;14074.437022518985;12741.362931853186;13172.802407963696;13267.469301227842;13505.324733763076;13663;10464.424456654653;10587.258891877495;10868.728281793019;10974;11783.941080294544;7723;8471.237588617054;10248.64129755455;11091.389973551532;11104 15:48:34 INFO - PROCESS | 1636 | __end_tp_report 15:48:34 INFO - PROCESS | 1636 | __start_cc_report 15:48:34 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,365 15:48:34 INFO - PROCESS | 1636 | __end_cc_report 15:48:34 INFO - PROCESS | 1636 | __startTimestamp1488498514634__endTimestamp 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | ------- Summary: start ------- 15:48:34 INFO - PROCESS | 1636 | Number of tests: 6 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5934.02 Median:6176.47 stddev:681.35 (11.0%) stddev-sans-first:657.48 15:48:34 INFO - PROCESS | 1636 | Values: 3559.3 3582.6 3588.5 3594.4 3632.1 5967.9 6031.4 6043.6 6055.5 6071.2 5838.8 5940.7 6128.5 6135.8 6159.4 4511.5 4511.7 5450.4 5465.8 5516.9 5478.2 5500.1 5514.8 5520.5 5523.2 5318.7 5345.2 5493.1 5497.5 5525.6 4536.4 4567.8 4981.0 5037.9 5502.9 4551.3 5367.9 5471.9 5477.6 5500.0 5884.7 6089.9 6126.8 6138.8 6152.3 5457.7 6241.3 6244.3 6271.4 6299.9 6376.3 6387.6 6421.5 6484.8 6490.2 6105.5 6133.9 6140.0 6159.6 6164.1 6541.9 6549.2 6628.8 6634.5 6660.5 6299.0 6322.4 6323.4 6327.1 6341.7 6176.5 6212.9 6455.5 6463.2 6485.0 6210.0 6257.8 6273.2 6331.5 6336.0 6293.9 6317.1 6331.9 6335.0 6357.1 5072.4 5179.6 5180.2 5189.5 5234.7 4566.4 4577.9 4582.6 4597.6 4653.5 5954.0 6130.0 6160.8 6168.9 6205.5 5188.4 6433.2 6435.1 6468.2 6487.6 6010.9 6026.9 6092.9 6122.4 6142.1 5680.1 5688.5 5689.3 5709.7 5720.0 6671.8 6699.9 6714.5 6730.1 6762.6 6210.2 6235.1 6314.1 6330.7 6466.8 6714.6 6718.9 6723.9 6728.8 6733.0 6363.9 6367.4 6369.7 6378.2 6380.6 6143.2 6274.4 6314.5 6389.1 6431.6 6141.3 6271.0 6294.2 6305.5 6312.1 6249.5 6254.7 6262.9 6281.0 6323.8 5409.2 5913.2 5998.0 6197.5 6247.7 6201.4 6219.8 6249.8 6279.3 6466.2 6164.9 6191.4 6196.5 6205.6 6226.6 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:68487.34 Median:40208.50 stddev:147285.85 (366.3%) stddev-sans-first:147610.57 15:48:34 INFO - PROCESS | 1636 | Values: 501.8 531.4 540.4 544.4 566.2 37793.8 40036.8 40138.0 40204.6 40233.2 41423.6 41693.2 41974.6 42087.0 42233.2 9536.8 9688.7 9710.7 9719.4 9726.2 9678.2 9730.8 9761.8 9764.6 9802.0 9667.6 9708.9 9724.6 9725.3 9740.0 9643.8 9708.4 9710.4 9720.4 9736.6 9602.7 9611.0 9649.5 9699.6 9756.4 39189.0 39756.8 39949.0 40030.0 40282.2 2752.6 3316.1 3338.4 3381.5 3418.7 29307.7 30081.7 30100.9 30427.1 30729.7 3136.8 3170.1 3321.4 3357.4 3361.5 489298.0 490067.0 490205.0 491331.0 491858.0 50945.7 61772.7 62220.8 62346.4 62483.7 63751.4 64139.0 64349.7 64484.7 64662.7 15751.2 15952.8 15981.3 15990.2 16003.2 12187.3 12206.8 12258.0 12263.0 12323.8 15374.5 17132.1 17667.4 17848.0 17979.0 5508.3 5825.1 6279.4 6337.3 6411.6 876.6 958.9 1000.0 1031.4 1054.9 30351.0 30412.8 30455.7 31015.0 31150.8 865.7 931.9 957.0 1065.6 1079.7 12472.6 13325.6 15089.0 15343.5 16533.8 448923.0 593386.0 596861.0 597950.0 598126.0 58316.7 58595.4 58840.1 58944.7 59107.0 1334.2 1357.7 1399.7 1405.2 1410.4 30250.4 31391.5 31405.7 31439.4 31879.0 51572.5 51996.7 52155.5 52624.5 52711.5 587614.0 588244.0 588501.1 589903.0 594163.0 24071.0 25137.5 26343.7 26446.2 27055.0 46348.6 46535.5 47352.5 47749.0 48090.0 36792.0 37960.1 38256.6 39639.2 39665.6 33364.0 36937.0 38989.6 39755.6 40212.4 37530.2 38005.4 38427.4 38989.6 39014.6 27896.0 29494.9 31860.7 34450.8 34462.3 21567.8 25571.5 25879.4 26275.7 27182.6 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:38394.37 Median:29410.49 stddev:105992.54 (360.4%) stddev-sans-first:106251.44 15:48:34 INFO - PROCESS | 1636 | Values: 378.1 378.4 386.8 393.9 414.0 18877.3 19782.7 20361.3 20438.5 20445.9 15571.8 18838.2 18902.8 19064.2 19439.8 5826.5 5842.5 5969.9 6045.4 6086.0 5886.3 5901.2 5962.9 6005.1 6016.0 5962.6 7269.8 7623.9 7658.3 7658.9 6479.0 7326.1 7572.7 7642.7 7766.8 7214.5 7447.0 7479.7 7480.8 7500.1 28050.4 28512.6 28543.9 28776.6 29129.3 2214.0 2298.5 2394.8 2490.7 2648.6 16377.0 16582.3 16907.9 16941.8 17733.6 1927.3 2006.5 2023.5 2041.8 2065.4 118779.4 122021.4 123242.0 125709.0 129527.0 22102.4 22400.9 22632.3 22775.2 22894.9 29691.7 36221.6 36566.9 37119.0 37146.3 8777.3 10520.2 10685.5 10880.6 10962.3 8725.6 8730.5 8747.5 8898.8 8939.5 8128.3 8254.0 10144.1 10154.9 10251.6 3921.5 3940.3 3941.5 3962.6 3990.7 847.8 855.5 867.8 902.1 988.1 17570.8 20494.6 21049.9 21088.5 21160.8 957.4 957.8 959.2 960.6 991.0 10178.5 10606.8 10623.9 10682.3 10689.0 616673.0 626771.0 633945.0 647888.0 648088.0 143931.0 144279.0 144508.0 144928.3 145991.0 926.4 931.0 951.3 951.4 974.8 17704.1 18538.1 18572.6 18667.3 18694.3 43369.0 58693.7 59173.7 59651.7 60437.0 82527.0 82545.3 83303.6 83314.0 86113.6 8137.0 8396.8 8414.7 8466.9 8478.0 11922.5 12114.6 12844.9 13183.9 13687.1 11378.1 11422.5 11536.0 11573.2 11859.8 11153.9 11222.6 11314.8 11527.7 11635.4 8882.5 10043.0 11338.4 11727.4 11766.4 8387.7 8424.1 8450.2 8559.5 8686.3 10154.8 10189.0 10434.7 11323.7 11521.9 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5433.71 Median:4817.22 stddev:2988.28 (62.0%) stddev-sans-first:2982.08 15:48:34 INFO - PROCESS | 1636 | Values: 1505.6 1532.6 1617.2 1713.3 1850.2 7640.4 7899.9 8131.1 8574.0 8574.0 7556.5 8052.2 8359.9 8415.1 8437.4 2250.4 2253.1 2266.4 2275.2 2288.8 2131.7 2359.9 2411.4 2434.5 2449.4 2328.0 2362.4 2374.1 2383.3 2409.3 2358.4 2367.4 2393.4 2393.9 2394.5 2383.1 2397.8 2402.3 2407.5 2417.3 8299.5 8469.7 8470.2 8475.8 8508.7 5081.3 5199.3 5203.3 5216.3 5228.1 3541.6 3552.8 3562.8 3633.0 3669.1 1065.0 1084.8 1114.2 1126.3 1136.7 9298.5 10828.9 10895.7 10915.9 10948.5 4496.0 4662.2 4747.9 4761.7 4810.3 8220.1 9466.8 9555.8 9563.5 9581.8 5161.2 5187.7 5197.6 5214.1 5238.0 4339.3 4344.6 4729.1 4731.5 4806.0 3581.8 3847.7 3886.2 3943.5 3999.7 1303.8 1308.1 1315.0 1324.6 1328.0 754.6 773.9 891.1 897.0 906.2 5507.2 5627.3 5752.5 5882.0 5888.6 388.5 429.4 454.2 456.7 461.5 3369.4 3483.4 3622.2 3859.7 3913.7 9842.3 10087.8 10181.8 10192.6 10198.7 10116.6 10160.0 10165.2 10179.9 10237.2 7613.3 7773.5 7860.4 7899.6 7960.6 5113.4 5151.7 5167.2 5326.6 5363.5 9326.9 9419.0 9452.1 9454.5 9487.9 9280.4 9335.3 9444.4 9604.5 9652.1 7056.2 7105.9 7182.5 7216.5 7249.4 7692.0 7778.5 7792.1 7805.0 7858.6 6797.3 7033.4 7162.6 7210.9 7255.9 6948.4 7011.4 7067.7 7147.4 7184.4 6739.0 6754.3 6765.6 6790.1 6824.2 4443.6 4477.1 4509.3 4519.2 4520.1 4624.3 4720.8 4782.8 4824.2 4916.5 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4584.94 Median:5095.63 stddev:1440.76 (28.3%) stddev-sans-first:1408.72 15:48:34 INFO - PROCESS | 1636 | Values: 315.7 319.6 320.2 326.5 326.5 4715.8 4991.4 5481.6 5530.8 5622.6 4954.0 5341.8 5389.2 5393.7 5412.2 3947.6 4022.2 4047.3 4088.4 4177.9 3383.5 4104.1 4109.7 4132.6 4167.2 4055.5 4134.9 4152.1 4157.9 4165.0 3821.6 4000.6 4065.0 4087.6 4132.3 2795.9 3201.8 3564.4 3576.0 3588.0 4606.0 5340.5 5376.4 5389.4 5395.4 3915.4 4511.8 4728.5 4744.9 4787.8 5215.6 5697.9 5707.4 5712.2 5796.1 3550.6 3925.5 3925.5 3937.4 3950.9 4975.9 5080.9 5583.9 5738.5 6085.9 3880.0 3944.9 3967.7 3974.9 3982.6 3710.9 3881.0 4011.4 4050.9 4052.0 4062.6 4727.8 4731.9 4735.5 4743.7 4720.1 4895.7 5404.9 5818.8 5854.6 1089.2 1140.6 1159.8 1162.0 1315.9 789.4 853.2 873.0 899.2 907.2 4837.3 4988.7 5057.6 5110.3 5150.7 5074.9 5319.8 5329.3 5695.9 5738.4 4654.8 4659.1 4798.0 4823.5 5128.6 2643.8 2703.7 2899.2 2908.4 2952.0 5426.4 5472.9 5586.6 5732.4 6006.2 5691.6 5704.6 5780.6 6120.5 6133.1 5503.6 5857.7 6218.9 6311.0 6321.4 4250.5 5076.9 5316.4 6013.9 6087.6 5410.5 5742.1 5867.6 5940.8 5976.5 5566.9 5645.6 5825.9 6270.1 6320.5 3356.4 3395.5 3435.8 3990.9 4027.3 5241.8 5399.8 5722.0 5960.4 6065.7 5438.9 5828.9 5845.7 5852.4 5915.9 4555.7 5037.9 5458.4 5636.9 5776.7 5626.5 5636.4 5666.9 5685.4 5714.3 5044.9 5528.1 5676.4 5842.4 5881.7 5206.7 5680.9 5735.4 5783.0 5841.8 15:48:34 INFO - PROCESS | 1636 | 15:48:34 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20964.59 Median:4433.74 stddev:30898.47 (696.9%) stddev-sans-first:30947.70 15:48:34 INFO - PROCESS | 1636 | Values: 704.2 724.5 742.9 783.6 809.9 13818.5 14558.0 14628.6 14642.3 14671.6 13081.0 13098.0 13115.9 13122.7 13228.6 691.7 709.1 836.1 871.9 886.0 773.9 859.3 871.6 873.8 875.4 921.5 929.2 929.5 931.8 932.1 874.5 879.3 916.4 921.9 922.3 650.6 661.4 685.4 687.9 690.2 13810.4 13920.9 13981.5 14024.4 14561.1 14228.0 14285.9 14387.6 14467.4 14613.7 11418.7 11505.6 11623.0 11703.4 11755.4 3699.5 3791.6 3806.5 3844.4 3849.8 70134.3 89178.0 94695.0 95435.0 95581.0 10866.9 11094.9 11103.0 11188.1 11245.8 22265.7 22360.1 22432.4 22603.8 22637.3 7933.5 7934.3 7967.9 8109.7 8208.3 4355.5 4371.0 4401.8 4465.7 4470.7 3491.1 3600.7 3602.5 3678.9 3841.0 691.0 696.9 703.5 706.2 709.2 531.9 664.2 678.5 691.7 699.1 8704.2 9315.3 9366.0 9522.5 9648.8 589.7 597.4 632.2 667.9 670.1 4145.3 4323.0 4395.5 4704.1 4790.4 117239.4 118831.4 120804.0 120869.0 123910.4 70131.0 84115.3 84355.6 108008.0 109740.0 77340.8 80273.6 82921.6 83045.0 85939.7 9385.4 9905.2 9932.0 9944.0 9966.6 54571.7 54795.0 54952.3 55362.4 56119.0 74420.6 77546.1 79845.0 79921.5 82232.4 10726.5 12318.4 12357.1 12396.0 12687.1 11682.9 13737.8 14462.0 14503.4 14887.2 11606.8 12612.2 13414.3 13522.3 14080.4 13505.5 13632.8 13643.0 13795.7 14074.4 12741.4 13172.8 13267.5 13505.3 13663.0 10464.4 10587.3 10868.7 10974.0 11783.9 7723.0 8471.2 10248.6 11091.4 11104.0 15:48:34 INFO - PROCESS | 1636 | -------- Summary: end -------- 15:48:34 INFO - PROCESS | 1636 | 15:48:35 INFO - TEST-INFO | 1636: exit 0 15:48:35 INFO - TEST-OK | dromaeo_css | took 1114932ms 15:48:35 INFO - TEST-START | kraken 15:48:35 INFO - operating with platform_type : mac_ 15:48:35 INFO - Initialising browser for kraken test... 15:48:35 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpCUcD4x/profile http://localhost:49235/getInfo.html) 15:48:38 INFO - PROCESS | 1648 | 2017-03-02 15:48:38.133 plugin-container[1650:9997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 15:48:38 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:38 INFO - PROCESS | 1648 | 2017-03-02 15:48:38.133 plugin-container[1650:9997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 15:48:38 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:38 INFO - PROCESS | 1648 | 2017-03-02 15:48:38.134 plugin-container[1650:9997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:48:38 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 15:48:38 INFO - PROCESS | 1648 | colorDepth:24 15:48:38 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 15:48:38 INFO - PROCESS | 1648 | __metrics 15:48:38 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 15:48:39 INFO - TEST-INFO | 1648: exit 0 15:48:39 INFO - Browser initialized. 15:48:39 INFO - Running cycle 1/1 for kraken test... 15:48:39 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpCUcD4x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:41 INFO - PROCESS | 1651 | 2017-03-02 15:48:41.591 plugin-container[1652:10120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842f, name = 'com.apple.tsm.portname' 15:48:41 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:41 INFO - PROCESS | 1651 | 2017-03-02 15:48:41.592 plugin-container[1652:10120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 15:48:41 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:48:41 INFO - PROCESS | 1651 | 2017-03-02 15:48:41.592 plugin-container[1652:10120] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:48:51 INFO - PROCESS | 1651 | RSS: Main: 177434624 15:48:51 INFO - PROCESS | 1651 | 15:48:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 15:48:55 INFO - PROCESS | 1651 | RSS: Main: 190607360 15:48:55 INFO - PROCESS | 1651 | 15:48:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 15:48:58 INFO - PROCESS | 1651 | RSS: Main: 198115328 15:48:58 INFO - PROCESS | 1651 | 15:49:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 15:49:02 INFO - PROCESS | 1651 | RSS: Main: 201687040 15:49:02 INFO - PROCESS | 1651 | 15:49:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 15:49:04 INFO - PROCESS | 1651 | RSS: Main: 202133504 15:49:04 INFO - PROCESS | 1651 | 15:49:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:49:07 INFO - PROCESS | 1651 | RSS: Main: 202264576 15:49:07 INFO - PROCESS | 1651 | 15:49:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 15:49:10 INFO - PROCESS | 1651 | RSS: Main: 205967360 15:49:10 INFO - PROCESS | 1651 | 15:49:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 15:49:13 INFO - PROCESS | 1651 | RSS: Main: 207372288 15:49:13 INFO - PROCESS | 1651 | 15:49:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 15:49:17 INFO - PROCESS | 1651 | RSS: Main: 206614528 15:49:17 INFO - PROCESS | 1651 | 15:49:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:49:19 INFO - PROCESS | 1651 | RSS: Main: 206508032 15:49:19 INFO - PROCESS | 1651 | 15:49:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:49:22 INFO - PROCESS | 1651 | RSS: Main: 206143488 15:49:22 INFO - PROCESS | 1651 | 15:49:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:49:24 INFO - PROCESS | 1651 | RSS: Main: 206049280 15:49:24 INFO - PROCESS | 1651 | 15:49:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:49:28 INFO - PROCESS | 1651 | RSS: Main: 206327808 15:49:28 INFO - PROCESS | 1651 | 15:49:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:49:31 INFO - PROCESS | 1651 | RSS: Main: 206503936 15:49:31 INFO - PROCESS | 1651 | 15:49:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 15:49:34 INFO - PROCESS | 1651 | RSS: Main: 208318464 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | __start_tp_report 15:49:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 15:49:34 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 15:49:34 INFO - PROCESS | 1651 | |i|pagename|runs| 15:49:34 INFO - PROCESS | 1651 | |0;ai-astar;106;116;119;118;120;120;119;120;118;118 15:49:34 INFO - PROCESS | 1651 | |1;audio-beat-detection;140;107;108;154;112;110;114;115;160;99 15:49:34 INFO - PROCESS | 1651 | |2;audio-dft;195;201;195;197;194;194;196;241;218;192 15:49:34 INFO - PROCESS | 1651 | |3;audio-fft;79;60;58;83;58;59;58;59;77;60 15:49:34 INFO - PROCESS | 1651 | |4;audio-oscillator;87;69;84;73;70;70;84;73;99;117 15:49:34 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;93;87;88;85;88;84;97;85;91;114 15:49:34 INFO - PROCESS | 1651 | |6;imaging-darkroom;95;96;93;95;93;94;95;95;95;93 15:49:34 INFO - PROCESS | 1651 | |7;imaging-desaturate;89;91;87;86;91;87;91;97;89;127 15:49:34 INFO - PROCESS | 1651 | |8;json-parse-financial;55;55;55;56;58;60;59;60;59;59 15:49:34 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;68;71;70;85;86;74;66;71;69;73 15:49:34 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;85;99;100;98;69;94;102;101;101;76 15:49:34 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;131;142;153;152;159;140;141;141;184;155 15:49:34 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;164;168;163;162;163;164;163;162;208;194 15:49:34 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;62;73;67;64;66;64;65;70;84;83 15:49:34 INFO - PROCESS | 1651 | __end_tp_report 15:49:34 INFO - PROCESS | 1651 | __start_cc_report 15:49:34 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,929 15:49:34 INFO - PROCESS | 1651 | __end_cc_report 15:49:34 INFO - PROCESS | 1651 | __startTimestamp1488498574020__endTimestamp 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | ------- Summary: start ------- 15:49:34 INFO - PROCESS | 1651 | Number of tests: 14 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:117.40 Median:119.00 stddev:4.20 (3.5%) stddev-sans-first:1.32 15:49:34 INFO - PROCESS | 1651 | Values: 106.0 116.0 119.0 118.0 120.0 120.0 119.0 120.0 118.0 118.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:127.50 stddev:21.34 (16.7%) stddev-sans-first:21.61 15:49:34 INFO - PROCESS | 1651 | Values: 140.0 107.0 108.0 154.0 112.0 110.0 114.0 115.0 160.0 99.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:202.30 Median:196.50 stddev:15.51 (7.9%) stddev-sans-first:16.22 15:49:34 INFO - PROCESS | 1651 | Values: 195.0 201.0 195.0 197.0 194.0 194.0 196.0 241.0 218.0 192.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:65.10 Median:60.00 stddev:10.18 (17.0%) stddev-sans-first:9.48 15:49:34 INFO - PROCESS | 1651 | Values: 79.0 60.0 58.0 83.0 58.0 59.0 58.0 59.0 77.0 60.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:78.50 stddev:15.50 (19.7%) stddev-sans-first:16.36 15:49:34 INFO - PROCESS | 1651 | Values: 87.0 69.0 84.0 73.0 70.0 70.0 84.0 73.0 99.0 117.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:88.00 stddev:8.97 (10.2%) stddev-sans-first:9.49 15:49:34 INFO - PROCESS | 1651 | Values: 93.0 87.0 88.0 85.0 88.0 84.0 97.0 85.0 91.0 114.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:95.00 stddev:1.07 (1.1%) stddev-sans-first:1.12 15:49:34 INFO - PROCESS | 1651 | Values: 95.0 96.0 93.0 95.0 93.0 94.0 95.0 95.0 95.0 93.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:90.00 stddev:12.18 (13.5%) stddev-sans-first:12.81 15:49:34 INFO - PROCESS | 1651 | Values: 89.0 91.0 87.0 86.0 91.0 87.0 91.0 97.0 89.0 127.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:59.00 stddev:2.12 (3.6%) stddev-sans-first:2.03 15:49:34 INFO - PROCESS | 1651 | Values: 55.0 55.0 55.0 56.0 58.0 60.0 59.0 60.0 59.0 59.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:72.00 stddev:6.83 (9.5%) stddev-sans-first:6.97 15:49:34 INFO - PROCESS | 1651 | Values: 68.0 71.0 70.0 85.0 86.0 74.0 66.0 71.0 69.0 73.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:92.50 Median:80.50 stddev:11.77 (14.6%) stddev-sans-first:12.17 15:49:34 INFO - PROCESS | 1651 | Values: 85.0 99.0 100.0 98.0 69.0 94.0 102.0 101.0 101.0 76.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:152.50 stddev:14.76 (9.7%) stddev-sans-first:14.00 15:49:34 INFO - PROCESS | 1651 | Values: 131.0 142.0 153.0 152.0 159.0 140.0 141.0 141.0 184.0 155.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:164.00 stddev:16.19 (9.9%) stddev-sans-first:16.97 15:49:34 INFO - PROCESS | 1651 | Values: 164.0 168.0 163.0 162.0 163.0 164.0 163.0 162.0 208.0 194.0 15:49:34 INFO - PROCESS | 1651 | 15:49:34 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:68.50 stddev:7.89 (11.5%) stddev-sans-first:7.84 15:49:34 INFO - PROCESS | 1651 | Values: 62.0 73.0 67.0 64.0 66.0 64.0 65.0 70.0 84.0 83.0 15:49:34 INFO - PROCESS | 1651 | -------- Summary: end -------- 15:49:34 INFO - PROCESS | 1651 | 15:49:35 INFO - TEST-INFO | 1651: exit 0 15:49:35 INFO - TEST-OK | kraken | took 59766ms 15:49:35 INFO - SUITE-END | took 1174s 15:49:35 INFO - Completed test suite (00:19:34) 15:49:35 INFO - javascript benchmark 15:49:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7446.82226707253, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3559.3415218184637, 3582.6238244984274, 3588.4976103345552, 3594.3889538778403, 3632.110133017411, 5967.880642387155, 6031.4270144336215, 6043.6373817570975, 6055.485283750881, 6071.150038994533, 5838.795642152526, 5940.673262970535, 6128.540359473035, 6135.846603834906, 6159.445649891509, 4511.548845115495, 4511.706739061967, 5450.427705090968, 5465.845927238166, 5516.862078448051, 5478.205660179272, 5500.092484740033, 5514.80698175564, 5520.503154716075, 5523.226748255248, 5318.654287471321, 5345.198220266952, 5493.148561972901, 5497.505224529811, 5525.585581081415, 4536.410266665345, 4567.840125595588, 4981.0, 5037.899242015176, 5502.862428439281, 4551.271796512575, 5367.892642147174, 5471.863203419906, 5477.616566840323, 5500.009998200322, 5884.705764711747, 6089.908651370233, 6126.846828829315, 6138.815835524942, 6152.292486364081, 5457.727113644302, 6241.3446588108045, 6244.31312555623, 6271.404216599416, 6299.9370006299805, 6376.33048529907, 6387.552871299011, 6421.454176395012, 6484.773032943872, 6490.156279683658, 6105.542084343692, 6133.938660613426, 6139.96930015356, 6159.568830181934, 6164.075388691687, 6541.934580654228, 6549.181352330958, 6628.801135965929, 6634.469242460592, 6660.467162627075, 6298.968505157445, 6322.430981211713, 6323.430891219812, 6327.082573026885, 6341.682915854189, 6176.474999625082, 6212.937870621236, 6455.4512866406885, 6463.224413070461, 6485.027245913151, 6209.96895015522, 6257.812265631947, 6273.1844860167885, 6331.493480521639, 6336.049592561153, 6293.874122517615, 6317.147185129948, 6331.905021424683, 6335.0, 6357.11000459936, 5072.365954255718, 5179.585633149339, 5180.197069454241, 5189.455107213764, 5234.738263086831, 4566.383538222364, 4577.908441831145, 4582.587567118975, 4597.586217240464, 4653.488116307203, 5953.970230148821, 6130.0, 6160.784372546939, 6168.907466388007, 6205.503559715212, 5188.351456067991, 6433.195850518685, 6435.06691529735, 6468.223813142453, 6487.643179625166, 6010.879782404328, 6026.9095963560585, 6092.939070609237, 6122.398155112068, 6142.078688196717, 5680.0627896397455, 5688.544916406678, 5689.260396148474, 5709.74306156224, 5719.970405326998, 6671.833204169838, 6699.899501507482, 6714.462842972648, 6730.057791909038, 6762.560433571802, 6210.223722034746, 6235.064740288993, 6314.052892066226, 6330.715117819801, 6466.805995820133, 6714.597124172566, 6718.899216511659, 6723.899141512783, 6728.798136055926, 6732.966335168293, 6363.936360636334, 6367.42693157618, 6369.7452101915405, 6378.202724659418, 6380.585261957958, 6143.201383820075, 6274.403931626488, 6314.463270622048, 6389.137466442156, 6431.5819471734185, 6141.301904043499, 6270.968645156928, 6294.181756371642, 6305.5270854687, 6312.116303717575, 6249.500039996698, 6254.718537665725, 6262.937370626236, 6281.0, 6323.7786677466065, 5409.188621706776, 5913.201717768218, 5997.970010150096, 6197.4732147767945, 6247.68761561911, 6201.379862013762, 6219.813405597839, 6249.812505624838, 6279.309275979547, 6466.159399278064, 6164.938350616437, 6191.442770150528, 6196.504279657706, 6205.596636218631, 6226.62640241587], "value": 5891.544697713838, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.84944516645, 531.4260598553562, 540.370468404309, 544.4229117135835, 566.2355819643482, 37793.81103094484, 40036.79981600095, 40138.0, 40204.59795402045, 40233.19533609326, 41423.58576414235, 41693.166136677246, 41974.58025419745, 42087.0, 42233.155336893244, 9536.809263814719, 9688.66089686861, 9710.70867873965, 9719.368241064343, 9726.17327527161, 9678.17735492484, 9730.805383892319, 9761.804763904718, 9764.560594773253, 9802.0, 9667.5649595768, 9708.902910970906, 9724.56239469222, 9725.27060470464, 9740.0, 9643.80712385752, 9708.368956017906, 9710.417374957526, 9720.416774993522, 9736.561854716554, 9602.66390676323, 9611.038896110333, 9649.469279189641, 9699.563519641633, 9756.414615123116, 39189.0, 39756.801215994026, 39949.00127496807, 40030.0, 40282.19435611301, 2752.573351130558, 3316.1046517440395, 3338.3824291465594, 3381.4758712399616, 3418.6581341865863, 29307.706922930924, 30081.699183008328, 30100.946466873775, 30427.087187384415, 30729.692703072906, 3136.8431578421214, 3170.1282147409415, 3321.368939901411, 3357.362101200764, 3361.5293858859777, 489298.0, 490067.0, 490205.0, 491331.0, 491857.99999999645, 50945.74527127341, 61772.69113654493, 62220.75558488895, 62346.37653623406, 62483.687581561804, 63751.36248637454, 64139.0, 64349.67825160844, 64484.677576611815, 64662.676686616265, 15751.212439377985, 15952.840471595367, 15981.28084236212, 15990.200489975454, 16003.199840008187, 12187.268763874195, 12206.755864882653, 12258.0, 12263.0, 12323.75352492945, 15374.461893833886, 17132.057736824594, 17667.38164164248, 17848.0, 17979.01115438637, 5508.256385387921, 5825.0679891217205, 6279.372062793684, 6337.33457986914, 6411.647359395185, 876.6274333408276, 958.8753462049889, 999.9950000249952, 1031.4224034540682, 1054.9208809339177, 30351.0, 30412.847935760623, 30455.69544304573, 31015.0, 31150.844245778626, 865.6796985115548, 931.9487428191379, 956.9856452153223, 1065.64300959178, 1079.6976846482996, 12472.625821225378, 13325.600231992861, 15089.0, 15343.53969380898, 16533.83466165347, 448923.0, 593386.0, 596861.0, 597950.0, 598126.0, 58316.708416457644, 58595.414045859, 58840.11739823906, 58944.705276474204, 59107.0, 1334.179479620046, 1357.7148798752175, 1399.6850708590487, 1405.1779708870222, 1410.4062409477226, 30250.394992100475, 31391.529127063568, 31405.68594314119, 31439.371212575163, 31879.0, 51572.48427515677, 51996.74001629968, 52155.47844521506, 52624.473755261955, 52711.47288527219, 587614.0, 588244.0, 588501.057494727, 589903.0, 594163.0, 24071.037158513464, 25137.49725005526, 26343.736562634127, 26446.239974588996, 27055.0, 46348.609541713806, 46535.53464465447, 47352.52647473481, 47749.0, 48090.03819923652, 36792.0, 37960.05099872525, 38256.61743382642, 39639.2072158561, 39665.60334396735, 33363.999080027636, 36937.0, 38989.610103898594, 39755.60244397519, 40212.3968140484, 37530.24939501249, 38005.42991855069, 38427.35963376908, 38989.610103898594, 39014.6098539011, 27896.0, 29494.852525737235, 31860.681393185772, 34450.82774586111, 34462.310753785285, 21567.784322157204, 25571.48857022812, 25879.353016174748, 26275.73724262733, 27182.592261116493], "value": 19691.75434369912, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.10200773163734, 378.40577261867406, 386.75054589789585, 393.90743175353794, 414.0187755020602, 18877.339293124725, 19782.703259451082, 20361.28735494256, 20438.489037774063, 20445.875476848767, 15571.766423503628, 18838.152283147258, 18902.81097189031, 19064.2374305028, 19439.805601943975, 5826.475617194452, 5842.503387212092, 5969.850753731148, 6045.395460453941, 6086.026235802273, 5886.323072846634, 5901.203337549418, 5962.8807423851495, 6005.099235114724, 6016.0, 5962.552808539355, 7269.818254543626, 7623.9237607624045, 7658.310752032316, 7658.923410765877, 6479.0, 7326.120865496148, 7572.7349542766015, 7642.694292228304, 7766.805829854242, 7214.458915581353, 7447.031885854858, 7479.663415146331, 7480.812979675497, 7500.137484189334, 28050.43899122006, 28512.572311415344, 28543.85728071367, 28776.568351474743, 29129.271768205752, 2214.0221402214015, 2298.5058212484396, 2394.832361734679, 2490.7384724603835, 2648.602709593557, 16377.0, 16582.336706531725, 16907.91546042262, 16941.83058169415, 17733.645327093516, 1927.258005667828, 2006.48834547191, 2023.5244717491378, 2041.836653067751, 2065.4320061982926, 118779.40610296893, 122021.38989304997, 123241.9999999991, 125709.0, 129527.0, 22102.447438813837, 22400.88799555992, 22632.321030369116, 22775.202867899545, 22894.885525572266, 29691.703082969325, 36221.637783622355, 36566.902992910254, 37119.0, 37146.32841522138, 8777.341699372444, 10520.210984176218, 10685.465726713634, 10880.619178328721, 10962.342259464458, 8725.607347669371, 8730.519821409884, 8747.475151490931, 8898.777530561658, 8939.463632182089, 8128.309093726981, 8254.0, 10144.087032167141, 10154.949225253975, 10251.58993640261, 3921.5490218624655, 3940.2513522430645, 3941.5270167580074, 3962.583928687446, 3990.7206495545033, 847.8257613205632, 855.5337341149088, 867.7700409391516, 902.1155104387605, 988.0662773678948, 17570.82429175692, 20494.590108197754, 21049.89475052615, 21088.472788180417, 21160.788392115883, 957.4351132831663, 957.7844984878346, 959.2374062620233, 960.6109525642127, 990.9603615855285, 10178.541965611565, 10606.840897386546, 10623.893761062445, 10682.305650132717, 10689.037986581245, 616673.0, 626771.0, 633945.0, 647888.0, 648088.0, 143931.0, 144279.0, 144508.0, 144928.27535862464, 145991.0, 926.36081104038, 930.9767255818658, 951.2627713522076, 951.357833462424, 974.7952929884661, 17704.114794260495, 18538.07309634486, 18572.628547428707, 18667.34664286798, 18694.34569790051, 43369.0, 58693.70653146707, 59173.70413147907, 59651.701741491015, 60437.0, 82527.0, 82545.349093019, 83303.5834820822, 83314.0, 86113.56943215244, 8137.0, 8396.832063358821, 8414.663413463637, 8466.872996905175, 8478.0, 11922.463489143183, 12114.575989840314, 12844.935775321064, 13183.868161318263, 13687.11033782801, 11378.08975281992, 11422.48598813072, 11535.96176344133, 11573.247738896946, 11859.822102668639, 11153.888461115284, 11222.55109795599, 11314.830277546009, 11527.654170374903, 11635.41822908868, 8882.467051976902, 10042.99570043019, 11338.380572440667, 11727.413629318671, 11766.352850593299, 8387.664493420194, 8424.073351931405, 8450.239478446969, 8559.486430814171, 8686.261667758312, 10154.796904062025, 10189.0, 10434.747830260418, 11323.716907077389, 11521.88478115208], "value": 10993.556548742094, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.6386467247858, 1532.5555588879226, 1617.223732608348, 1713.2975480053174, 1850.2229063793206, 7640.35057020154, 7899.881501777478, 8131.064927533335, 8573.957130214356, 8574.0, 7556.5466072035715, 8052.19478052196, 8359.874601880976, 8415.074341822394, 8437.409381343308, 2250.437390652337, 2253.0987604957986, 2266.433391652087, 2275.24916777463, 2288.8168946484325, 2131.6589345704692, 2359.893804778789, 2411.3850968003135, 2434.47658753368, 2449.3509220056612, 2327.976720232801, 2362.3857796972825, 2374.0622454130607, 2383.3207535852284, 2409.2772168349425, 2358.3750306168877, 2367.3844800351944, 2393.389685630171, 2393.904243830262, 2394.521095780842, 2383.0944241188413, 2397.7722116398913, 2402.2793162051316, 2407.4944261705064, 2417.2506522977933, 8299.460535065202, 8469.70356037542, 8470.195331443503, 8475.788105297417, 8508.702195423193, 5081.3140226069745, 5199.324087868591, 5203.271541984125, 5216.347956505437, 5228.137357336035, 3541.645835416438, 3552.7513074084823, 3562.8040457774814, 3633.0372451300427, 3669.0643885809213, 1064.9520771565221, 1084.7993121272589, 1114.1977776001265, 1126.2622981946838, 1136.6817291158488, 9298.488583127924, 10828.891711082946, 10895.673129806119, 10915.890841091566, 10948.507317170746, 4495.955040449619, 4662.184117779375, 4747.928781068286, 4761.7142971421135, 4810.278458231223, 8220.095789463154, 9466.810663786686, 9555.761105972406, 9563.521823908777, 9581.808363832684, 5161.174212126042, 5187.714675692873, 5197.558207552401, 5214.087534681492, 5238.030964271622, 4339.34909763538, 4344.587264209895, 4729.125111854318, 4731.455882573479, 4805.990741944231, 3581.8209089545417, 3847.7306588539095, 3886.242182774332, 3943.5464921533817, 3999.7000224983244, 1303.8135296879793, 1308.1235572166672, 1314.9802752958713, 1324.6158614001831, 1328.0106320791072, 754.6453167011496, 773.918738532446, 891.1222445890793, 896.9506677132821, 906.2161230535539, 5507.173923911445, 5627.296587926509, 5752.539796816244, 5882.0, 5888.587798854039, 388.5065966222881, 429.42671533503125, 454.15073811971166, 456.682605589119, 461.49235840575096, 3369.4103531882315, 3483.390406678821, 3622.2212224371888, 3859.6526312632, 3913.6673382762215, 9842.311038227255, 10087.789465264215, 10181.847272290921, 10192.592296308212, 10198.745031374126, 10116.645917392856, 10160.0, 10165.237607179492, 10179.949100254451, 10237.23220758446, 7613.276738709814, 7773.533587984739, 7860.371170306362, 7899.565523896241, 7960.601969901481, 5113.41195762492, 5151.742412879416, 5167.173252279767, 5326.627136100436, 5363.463653634605, 9326.860097098684, 9419.0, 9452.054794520493, 9454.527273636428, 9487.857682134636, 9280.350375473652, 9335.299852510952, 9444.433333999981, 9604.519774011133, 9652.083052110038, 7056.223815380202, 7105.893411598727, 7182.533135346186, 7216.530925489827, 7249.420046396381, 7692.0384951881015, 7778.49439786412, 7792.10390805042, 7804.9609751950875, 7858.5677787722225, 6797.286284940208, 7033.402160816388, 7162.570245785061, 7210.89183662235, 7255.891161632685, 6948.409385202314, 7011.368976792113, 7067.717291308496, 7147.428205743424, 7184.425245980414, 6738.966305168443, 6754.2570317266045, 6765.594064356154, 6790.117284752951, 6824.249332573512, 4443.600075993176, 4477.126960242721, 4509.2559727645885, 4519.209138400701, 4520.141173177086, 4624.28323609841, 4720.811167553259, 4782.808687652455, 4824.155772739827, 4916.532929371704], "value": 4342.63954291564, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.6811620263534, 319.56698673297683, 320.1835319934168, 326.505344403229, 326.5053444032292, 4715.787789549474, 4991.376077990251, 5481.643693159946, 5530.806421775239, 5622.606417550768, 4954.033963377143, 5341.839744807652, 5389.245505629215, 5393.651587103224, 5412.2384942464905, 3947.624975627318, 4022.175454031931, 4047.271491131595, 4088.3867419886956, 4177.874663760077, 3383.5263063171174, 4104.076582768883, 4109.650679692215, 4132.594917727972, 4167.166326897708, 4055.472788537501, 4134.855280065199, 4152.086540960999, 4157.896052598679, 4165.042040330711, 3821.617838216156, 4000.5599384067727, 4064.959350406488, 4087.550369459357, 4132.2768515509815, 2795.916122516328, 3201.823899685516, 3564.41187204111, 3575.964240357589, 3587.964120358789, 4606.0, 5340.54605358545, 5376.354837419495, 5389.416038334502, 5395.408354535382, 3915.4126880968083, 4511.751853648081, 4728.527147285278, 4744.881377965544, 4787.832425865078, 5215.556677682402, 5697.857553561153, 5707.3721890592005, 5712.200291959129, 5796.13058041293, 3550.5739311282555, 3925.548561915388, 3925.548561915388, 3937.42907278443, 3950.9012274693077, 4975.875603109951, 5080.923786143211, 5583.916241256425, 5738.454846789548, 6085.908711369333, 3880.0299925018744, 3944.928912321054, 3967.7222594418113, 3974.940375894364, 3982.6216509431556, 3710.925781484409, 3881.0491429599365, 4011.4183443400543, 4050.858219962287, 4052.0275133967634, 4062.5531191568903, 4727.763611819395, 4731.858044258678, 4735.455422626374, 4743.715377077387, 4720.12677654635, 4895.657303988686, 5404.945950540444, 5818.796342128006, 5854.560907931922, 1089.226649079147, 1140.6235942139074, 1159.7622487390065, 1162.0, 1315.9210447373189, 789.3527307607707, 853.2278288149234, 872.9825403491894, 899.1772528136681, 907.1971305394774, 4837.32277481153, 4988.6507944444265, 5057.570106540912, 5110.3356563646485, 5150.742462876841, 5074.898502030012, 5319.813806516753, 5329.267987903947, 5695.886082278331, 5738.426157384311, 4654.790534425959, 4659.091477161921, 4797.976010119997, 4823.541763532458, 5128.640995130379, 2643.788496920242, 2703.6890757562737, 2899.2461959890156, 2908.4473949949866, 2951.9557206641916, 5426.375966763872, 5472.890542189213, 5586.55307575393, 5732.369439361667, 6006.2191915051635, 5691.630044047124, 5704.632577480382, 5780.5953583248765, 6120.479759220516, 6133.080037994337, 5503.587230957694, 5857.736401861926, 6218.875622487615, 6310.968445157745, 6321.431071203614, 4250.489941207075, 5076.9338438928235, 5316.388615309289, 6013.909791353046, 6087.569421186106, 5410.509277299753, 5742.052561327334, 5867.647941123546, 5940.782139661445, 5976.491998180203, 5566.91649625264, 5645.612632420515, 5825.94174058254, 6270.122182894307, 6320.525960553069, 3356.3622911646708, 3395.524626552235, 3435.8282085895107, 3990.920181596294, 4027.3153563894793, 5241.816536421207, 5399.811006614749, 5722.0, 5960.433758792908, 6065.72704228302, 5438.864028399322, 5828.94171058284, 5845.707714614167, 5852.414758524284, 5915.911261330997, 4555.658325625657, 5037.874053148701, 5458.399576046552, 5636.943630563642, 5776.653400795965, 5626.465485778845, 5636.436356364331, 5666.914996274977, 5685.431456854281, 5714.342850572238, 5044.873878153076, 5528.143137813645, 5676.375598684059, 5842.444967728059, 5881.735321910439, 5206.713630750345, 5680.932296559811, 5735.369109398046, 5783.0, 5841.8454034820725], "value": 4120.1195927956305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.2288693880199, 724.5471580262335, 742.9257074292568, 783.6434422337837, 809.9352051835851, 13818.516351927685, 14558.0, 14628.634284142876, 14642.34109465074, 14671.559853204408, 13081.018923580747, 13098.0, 13115.868841311583, 13122.671933201675, 13228.603141905758, 691.72331067573, 709.0604948443333, 836.113719457374, 871.877937088808, 886.0120965123873, 773.8916551682769, 859.325429537813, 871.5685735560907, 873.7640836974014, 875.3959767760225, 921.4655499810127, 929.1683942871126, 929.4841363043508, 931.7810314576069, 932.1377725603824, 874.5277550122958, 879.2965627497977, 916.4363916191561, 921.9216366608779, 922.299052719931, 650.5608714117951, 661.4344735251387, 685.3831551603547, 687.9484038697118, 690.1683471416931, 13810.447582096704, 13920.93039534806, 13981.510647127403, 14024.439022439192, 14561.126332420088, 14228.0, 14285.85714142866, 14387.640308992255, 14467.421303147861, 14613.707725845423, 11418.71453213668, 11505.597304094317, 11623.0, 11703.356315402649, 11755.35345555994, 3699.519062521882, 3791.6018818023986, 3806.5241844769403, 3844.442555829417, 3849.8460061597502, 70134.2986570138, 89178.0, 94694.99999999932, 95435.0, 95581.04418955791, 10866.891331086747, 11094.889051109547, 11102.958426298776, 11188.054426226247, 11245.83131253032, 22265.66601500979, 22360.105595775985, 22432.439189020406, 22603.773962260166, 22637.320880373616, 7933.484323518953, 7934.325582325567, 7967.920320796834, 8109.675612975415, 8208.302294304931, 4355.477342718894, 4370.978145109254, 4401.823927042947, 4465.665075119379, 4470.709403888737, 3491.1097670093964, 3600.71194304455, 3602.5136606558303, 3678.9448158277646, 3841.0397400649836, 690.9585424874423, 696.8710788504143, 703.5216053083952, 706.1808302369227, 709.1561042359575, 531.9388270348959, 664.1598378051768, 678.4504551313353, 691.68182635987, 699.0597646165899, 8704.173103555153, 9315.34792564514, 9365.953170234105, 9522.523873806282, 9648.793900762405, 589.7257775134583, 597.3697748874891, 632.2444678609018, 667.8731041102175, 670.0518765946229, 4145.274197813619, 4322.984581388153, 4395.450568678915, 4704.129735998783, 4790.401199850019, 117239.41380293044, 118831.40584297196, 120804.0, 120869.0, 123910.38044809898, 70131.0, 84115.31769364701, 84355.5782221085, 108008.0, 109740.0, 77340.83988740173, 80273.59863200647, 82921.58539207265, 83045.0, 85939.71090433649, 9385.436873787594, 9905.207583393167, 9932.0, 9943.950280248553, 9966.55150518243, 54571.72714136404, 54795.0, 54952.299736188776, 55362.44637553734, 56119.0, 74420.62789686234, 77546.06134846674, 79845.0, 79921.49322281391, 82232.3553528938, 10726.49829023953, 12318.445669945053, 12357.073219508393, 12396.00831933425, 12687.11190216676, 11682.94158529202, 13737.793933091212, 14462.0, 14503.419863205776, 14887.181205033827, 11606.825897611374, 12612.243265404106, 13414.329283535979, 13522.323883805573, 14080.36638351256, 13505.459781608626, 13632.795508067189, 13643.0, 13795.724085518434, 14074.437022518985, 12741.362931853186, 13172.802407963696, 13267.469301227842, 13505.324733763076, 13663.0, 10464.424456654653, 10587.258891877495, 10868.728281793019, 10974.0, 11783.941080294544, 7723.0, 8471.237588617054, 10248.64129755455, 11091.389973551532, 11104.0], "value": 7472.426422454931, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 119.0, 118.0, 120.0, 120.0, 119.0, 120.0, 118.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 108.0, 154.0, 112.0, 110.0, 114.0, 115.0, 160.0, 99.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 201.0, 195.0, 197.0, 194.0, 194.0, 196.0, 241.0, 218.0, 192.0], "value": 202.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 60.0, 58.0, 83.0, 58.0, 59.0, 58.0, 59.0, 77.0, 60.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 84.0, 73.0, 70.0, 70.0, 84.0, 73.0, 99.0, 117.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 87.0, 88.0, 85.0, 88.0, 84.0, 97.0, 85.0, 91.0, 114.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 93.0, 95.0, 93.0, 94.0, 95.0, 95.0, 95.0, 93.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 87.0, 86.0, 91.0, 87.0, 91.0, 97.0, 89.0, 127.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 56.0, 58.0, 60.0, 59.0, 60.0, 59.0, 59.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 70.0, 85.0, 86.0, 74.0, 66.0, 71.0, 69.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 99.0, 100.0, 98.0, 69.0, 94.0, 102.0, 101.0, 101.0, 76.0], "value": 92.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 142.0, 153.0, 152.0, 159.0, 140.0, 141.0, 141.0, 184.0, 155.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 163.0, 162.0, 163.0, 164.0, 163.0, 162.0, 208.0, 194.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 73.0, 67.0, 64.0, 66.0, 64.0, 65.0, 70.0, 84.0, 83.0], "value": 69.8, "unit": "score"}]}]} 15:49:35 INFO - Return code: 0 15:49:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:49:35 ERROR - # TBPL SUCCESS # 15:49:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:49:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:49:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:49:35 INFO - 2017-03-02 15:49:35.331 screenresolution[1656:10498] starting screenresolution argv=screenresolution get 15:49:35 INFO - 2017-03-02 15:49:35.333 screenresolution[1656:10498] Display 0: 1600x1200x32@60 15:49:35 INFO - 2017-03-02 15:49:35.340 screenresolution[1657:10501] starting screenresolution argv=screenresolution list 15:49:35 INFO - Available Modes on Display 0 15:49:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:49:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:49:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:49:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:49:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:49:35 INFO - Intel Iris: 15:49:35 INFO - Chipset Model: Intel Iris 15:49:35 INFO - Type: GPU 15:49:35 INFO - Bus: Built-In 15:49:35 INFO - VRAM (Dynamic, Max): 1536 MB 15:49:35 INFO - Vendor: Intel (0x8086) 15:49:35 INFO - Device ID: 0x0a2e 15:49:35 INFO - Revision ID: 0x0009 15:49:35 INFO - Displays: 15:49:35 INFO - Display: 15:49:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:49:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:49:35 INFO - Display Serial Number: 1600x1200 60 15:49:35 INFO - Main Display: Yes 15:49:35 INFO - Mirror: Off 15:49:35 INFO - Online: Yes 15:49:35 INFO - Rotation: Supported 15:49:35 INFO - Return code: 0 15:49:35 INFO - Running post-action listener: _resource_record_post_action 15:49:35 INFO - [mozharness: 2017-03-02 23:49:35.583564Z] Finished run-tests step (success) 15:49:35 INFO - Running post-run listener: _resource_record_post_run 15:49:35 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 40421376; Write bytes: 498090496; Read time: 460; Write time: 1593 15:49:35 INFO - TinderboxPrint: CPU usage
54.3% 15:49:35 INFO - TinderboxPrint: I/O read bytes / time
40,421,376 / 460 15:49:35 INFO - TinderboxPrint: I/O write bytes / time
498,090,496 / 1,593 15:49:35 INFO - TinderboxPrint: CPU idle
2,187.7 (45.7%) 15:49:35 INFO - TinderboxPrint: CPU system
968.6 (20.2%) 15:49:35 INFO - TinderboxPrint: CPU user
1,629.3 (34.0%) 15:49:35 INFO - TinderboxPrint: Swap in / out
232,255,488 / 0 15:49:35 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 187924992; Write bytes: 186556416; Read time: 16969; Write time: 346 15:49:35 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38389760; Write bytes: 304620032; Read time: 435; Write time: 1216 15:49:36 INFO - Running post-run listener: _upload_blobber_files 15:49:36 INFO - Blob upload gear active. 15:49:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:36 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 15:49:36 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'] 15:49:36 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 15:49:36 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:49:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:49:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:37 INFO - (blobuploader) - INFO - Done attempting. 15:49:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:49:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:49:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:38 INFO - (blobuploader) - INFO - Done attempting. 15:49:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:40 INFO - (blobuploader) - INFO - Done attempting. 15:49:40 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:40 INFO - Return code: 0 15:49:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f5c780a02a50d0c2a0ef67f11c352c6020469f74779f003ff43a63927814335555442c6bedae8641a3e35fc0a3b689a1469d0a743dd37185a3923d738d20e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1c44649421651138cd6c39524a4188cd7d6de1cfb9098673d8c307972929264b80ab90c1786a98d186fd60d8806cf23a1b8acc50eff370e534d051e87c74add", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d08c539551e3a4f4d402e4748f015d2b70374d99174ce60e5e816a38f71c00ec92e201cd1b783301e6f0befd47614520732660795b30bcfa567968df26d15983"} 15:49:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:40 INFO - Contents: 15:49:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3f5c780a02a50d0c2a0ef67f11c352c6020469f74779f003ff43a63927814335555442c6bedae8641a3e35fc0a3b689a1469d0a743dd37185a3923d738d20e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1c44649421651138cd6c39524a4188cd7d6de1cfb9098673d8c307972929264b80ab90c1786a98d186fd60d8806cf23a1b8acc50eff370e534d051e87c74add", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d08c539551e3a4f4d402e4748f015d2b70374d99174ce60e5e816a38f71c00ec92e201cd1b783301e6f0befd47614520732660795b30bcfa567968df26d15983"} 15:49:40 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:40 INFO - Copying logs to upload dir... 15:49:40 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.926579 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-03-02 15:49:40.399385) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 15:49:40.402877) ========= 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.aA8z4bia0r/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.tTVuqSiSsI/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/3f5c780a02a50d0c2a0ef67f11c352c6020469f74779f003ff43a63927814335555442c6bedae8641a3e35fc0a3b689a1469d0a743dd37185a3923d738d20e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1c44649421651138cd6c39524a4188cd7d6de1cfb9098673d8c307972929264b80ab90c1786a98d186fd60d8806cf23a1b8acc50eff370e534d051e87c74add", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d08c539551e3a4f4d402e4748f015d2b70374d99174ce60e5e816a38f71c00ec92e201cd1b783301e6f0befd47614520732660795b30bcfa567968df26d15983"} build_url:https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/YDJLCKb9SzK7po3MH-bYeg/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/3f5c780a02a50d0c2a0ef67f11c352c6020469f74779f003ff43a63927814335555442c6bedae8641a3e35fc0a3b689a1469d0a743dd37185a3923d738d20e48", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d1c44649421651138cd6c39524a4188cd7d6de1cfb9098673d8c307972929264b80ab90c1786a98d186fd60d8806cf23a1b8acc50eff370e534d051e87c74add", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d08c539551e3a4f4d402e4748f015d2b70374d99174ce60e5e816a38f71c00ec92e201cd1b783301e6f0befd47614520732660795b30bcfa567968df26d15983"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-02 15:49:40.441510) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 15:49:40.441849) ========= 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.aA8z4bia0r/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.tTVuqSiSsI/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-02 15:49:40.490418) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 15:49:40.490881) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-02 15:49:40.491469) ========= ========= Total master_lag: 0.19 =========