builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0007 starttime: 1483409442.77 results: success (0) buildid: 20170102163320 builduid: 7cede652e5da4b41a2386eae4490339f revision: 67635f34bbdcf21966af1c285724e8f5b1e64833 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:42.773634) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:42.774042) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:42.774483) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:42.886027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:42.886473) ========= 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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-01-02 18:10:42-- 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% 171M=0s 2017-01-02 18:10:43 (171 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.899308 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:43.812848) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:43.813262) ========= 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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.029578 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:43.867430) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:43.867763) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 67635f34bbdcf21966af1c285724e8f5b1e64833 --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-aurora --rev 67635f34bbdcf21966af1c285724e8f5b1e64833 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-01-02 18:10:43,957 truncating revision to first 12 chars 2017-01-02 18:10:43,957 Setting DEBUG logging. 2017-01-02 18:10:43,957 attempt 1/10 2017-01-02 18:10:43,957 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/67635f34bbdc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-02 18:10:44,625 unpacking tar archive at: mozilla-aurora-67635f34bbdc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.938353 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:44.852221) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:44.852743) ========= script_repo_revision: 67635f34bbdcf21966af1c285724e8f5b1e64833 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:44.853413) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:44.853800) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-02 18:10:45.679024) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-02 18:10:45.679422) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:10:45 INFO - MultiFileLogger online at 20170102 18:10:45 in /builds/slave/test 18:10:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:10:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:10:45 INFO - {'append_to_log': False, 18:10:45 INFO - 'base_work_dir': '/builds/slave/test', 18:10:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:10:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:10:45 INFO - 'branch': 'Mozilla-Aurora', 18:10:45 INFO - 'buildbot_json_path': 'buildprops.json', 18:10:45 INFO - 'config_files': ('talos/mac_config.py',), 18:10:45 INFO - 'default_actions': ('clobber', 18:10:45 INFO - 'read-buildbot-config', 18:10:45 INFO - 'download-and-extract', 18:10:45 INFO - 'populate-webroot', 18:10:45 INFO - 'create-virtualenv', 18:10:45 INFO - 'install', 18:10:45 INFO - 'run-tests'), 18:10:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:10:45 INFO - 'download_minidump_stackwalk': True, 18:10:45 INFO - 'download_symbols': 'ondemand', 18:10:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:10:45 INFO - 'tooltool.py': '/tools/tooltool.py', 18:10:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:10:45 INFO - '/tools/misc-python/virtualenv.py')}, 18:10:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:10:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:10:45 INFO - 'installer_path': 'installer.exe', 18:10:45 INFO - 'log_level': 'info', 18:10:45 INFO - 'log_name': 'talos', 18:10:45 INFO - 'log_to_console': True, 18:10:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:10:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:10:45 INFO - 'opt_config_files': (), 18:10:45 INFO - 'pip_index': False, 18:10:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:10:45 INFO - 'cmd': ('bash', 18:10:45 INFO - '-c', 18:10:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:10:45 INFO - 'enabled': True, 18:10:45 INFO - 'halt_on_failure': False, 18:10:45 INFO - 'name': 'check_screen_resolution'},), 18:10:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:10:45 INFO - 'cmd': ('bash', 18:10:45 INFO - '-c', 18:10:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:10:45 INFO - 'enabled': True, 18:10:45 INFO - 'halt_on_failure': False, 18:10:45 INFO - 'name': 'check_screen_resolution'},), 18:10:45 INFO - 'run_cmd_checks_enabled': True, 18:10:45 INFO - 'sps_profile': False, 18:10:45 INFO - 'sps_profile_interval': 0, 18:10:45 INFO - 'suite': 'dromaeojs', 18:10:45 INFO - 'system_bits': '32', 18:10:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:10:45 INFO - 'title': 't-yosemite-r7-0007', 18:10:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:10:45 INFO - 'use_talos_json': True, 18:10:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:10:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:10:45 INFO - 'work_dir': 'build'} 18:10:45 INFO - [mozharness: 2017-01-03 02:10:45.882567Z] Running clobber step. 18:10:45 INFO - Running pre-action listener: _resource_record_pre_action 18:10:45 INFO - Running main action method: clobber 18:10:45 INFO - rmtree: /builds/slave/test/build 18:10:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:10:47 INFO - Running post-action listener: _resource_record_post_action 18:10:47 INFO - [mozharness: 2017-01-03 02:10:47.971445Z] Finished clobber step (success) 18:10:47 INFO - [mozharness: 2017-01-03 02:10:47.971612Z] Running read-buildbot-config step. 18:10:47 INFO - Running pre-action listener: _resource_record_pre_action 18:10:47 INFO - Running main action method: read_buildbot_config 18:10:47 INFO - Using buildbot properties: 18:10:47 INFO - { 18:10:47 INFO - "project": "", 18:10:47 INFO - "product": "firefox", 18:10:47 INFO - "script_repo_revision": "production", 18:10:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:10:47 INFO - "repository": "", 18:10:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:10:47 INFO - "buildid": "20170102163320", 18:10:47 INFO - "pgo_build": "False", 18:10:47 INFO - "basedir": "/builds/slave/test", 18:10:47 INFO - "buildnumber": 28, 18:10:47 INFO - "slavename": "t-yosemite-r7-0007", 18:10:47 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:10:47 INFO - "platform": "yosemite_r7", 18:10:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:10:47 INFO - "branch": "mozilla-aurora", 18:10:47 INFO - "repo_path": "releases/mozilla-aurora", 18:10:47 INFO - "revision": "67635f34bbdcf21966af1c285724e8f5b1e64833", 18:10:47 INFO - "stage_platform": "macosx64", 18:10:47 INFO - "builduid": "7cede652e5da4b41a2386eae4490339f", 18:10:47 INFO - "slavebuilddir": "test" 18:10:47 INFO - } 18:10:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 18:10:47 INFO - Running post-action listener: _resource_record_post_action 18:10:47 INFO - [mozharness: 2017-01-03 02:10:47.974955Z] Finished read-buildbot-config step (success) 18:10:47 INFO - [mozharness: 2017-01-03 02:10:47.975071Z] Running download-and-extract step. 18:10:47 INFO - Running pre-action listener: _resource_record_pre_action 18:10:47 INFO - Running main action method: download_and_extract 18:10:47 INFO - mkdir: /builds/slave/test/build/tests 18:10:47 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')]} 18:10:47 INFO - trying https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 18:10:47 INFO - Downloading https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:10:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 18:10:49 INFO - Downloaded 1199 bytes. 18:10:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 18:10:49 INFO - Using the following test package requirements: 18:10:49 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:10:49 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 18:10:49 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 18:10:49 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'jsshell-mac.zip'], 18:10:49 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 18:10:49 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 18:10:49 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 18:10:49 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 18:10:49 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 18:10:49 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 18:10:49 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 18:10:49 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:10:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:10:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:10:49 INFO - Fetch https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:10:49 INFO - Http code: 200 18:10:49 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 18:10:49 INFO - x-amz-cf-id: 430kINvdVLMeYmA8kHpBfEToEroxL-ubENgcB7Xzkh8TMl6b1E521g== 18:10:49 INFO - x-amz-version-id: e8vYvjP.UZv6Mv7SVAsvaX4KrBDLuNAM 18:10:50 INFO - Expected file size: 16268340 18:10:50 INFO - Obtained file size: 16268340 18:10:56 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:10:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 18:10:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:10:56 INFO - Fetch https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 18:10:57 INFO - Http code: 200 18:10:57 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 18:10:57 INFO - x-amz-cf-id: ouMh_rB-LaYYsxA690bijmRUjEzWlt6UZDQAM7zlCEbIot6cTlQvcA== 18:10:57 INFO - x-amz-version-id: e8vYvjP.UZv6Mv7SVAsvaX4KrBDLuNAM 18:10:57 INFO - Expected file size: 16268340 18:10:57 INFO - Obtained file size: 16268340 18:11:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 18:11:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:11:03 INFO - Fetch https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 18:11:04 INFO - Http code: 200 18:11:04 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 18:11:04 INFO - x-amz-cf-id: qv7yJRnY3o6BNdYOmrSaGH0A_GWCS39-Gkcmw5uP-jtSgQnguy3fxQ== 18:11:04 INFO - x-amz-version-id: W_QfejmlqvAzokYh.cKwMaGypZ2WlWps 18:11:04 INFO - Expected file size: 14409038 18:11:04 INFO - Obtained file size: 14409038 18:11:05 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')]} 18:11:05 INFO - trying https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:11:05 INFO - Downloading https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 18:11:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 18:11:09 INFO - Downloaded 109827499 bytes. 18:11:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:11:09 INFO - mkdir: /builds/slave/test/properties 18:11:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:11:09 INFO - Writing to file /builds/slave/test/properties/build_url 18:11:09 INFO - Contents: 18:11:09 INFO - build_url:https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 18:11:09 INFO - Running post-action listener: _resource_record_post_action 18:11:09 INFO - Running post-action listener: set_extra_try_arguments 18:11:09 INFO - [mozharness: 2017-01-03 02:11:09.777368Z] Finished download-and-extract step (success) 18:11:09 INFO - [mozharness: 2017-01-03 02:11:09.777461Z] Running populate-webroot step. 18:11:09 INFO - Running pre-action listener: _resource_record_pre_action 18:11:09 INFO - Running main action method: populate_webroot 18:11:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:11:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:11:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:11:09 INFO - '--tppagecycles', 18:11:09 INFO - '7'], 18:11:09 INFO - 'tests': ['tsvgm']}}, 18:11:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:11:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:11:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:11:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:11:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['damp', 'tps']}, 18:11:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'tests': ['damp', 'tps']}, 18:11:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['dromaeo_dom']}, 18:11:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:11:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['basic_compositor_video']}, 18:11:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:11:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['a11yr', 18:11:09 INFO - 'ts_paint', 18:11:09 INFO - 'tpaint', 18:11:09 INFO - 'sessionrestore', 18:11:09 INFO - 'sessionrestore_no_auto_restore', 18:11:09 INFO - 'tabpaint']}, 18:11:09 INFO - 'other-e10s': {'tests': ['a11yr', 18:11:09 INFO - 'ts_paint', 18:11:09 INFO - 'tpaint', 18:11:09 INFO - 'sessionrestore', 18:11:09 INFO - 'sessionrestore_no_auto_restore', 18:11:09 INFO - 'tabpaint']}, 18:11:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:11:09 INFO - 'ts_paint', 18:11:09 INFO - 'tpaint', 18:11:09 INFO - 'sessionrestore', 18:11:09 INFO - 'sessionrestore_no_auto_restore', 18:11:09 INFO - 'tabpaint']}, 18:11:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:11:09 INFO - 'ts_paint', 18:11:09 INFO - 'tpaint', 18:11:09 INFO - 'sessionrestore', 18:11:09 INFO - 'sessionrestore_no_auto_restore', 18:11:09 INFO - 'tabpaint']}, 18:11:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['a11yr', 18:11:09 INFO - 'ts_paint', 18:11:09 INFO - 'tpaint', 18:11:09 INFO - 'sessionrestore', 18:11:09 INFO - 'sessionrestore_no_auto_restore', 18:11:09 INFO - 'tabpaint']}, 18:11:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['a11yr', 18:11:09 INFO - 'ts_paint', 18:11:09 INFO - 'tpaint', 18:11:09 INFO - 'sessionrestore', 18:11:09 INFO - 'sessionrestore_no_auto_restore', 18:11:09 INFO - 'tabpaint']}, 18:11:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['tsvgx', 18:11:09 INFO - 'tsvgr_opacity', 18:11:09 INFO - 'tart', 18:11:09 INFO - 'tscrollx', 18:11:09 INFO - 'cart']}, 18:11:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:11:09 INFO - 'tsvgr_opacity', 18:11:09 INFO - 'tart', 18:11:09 INFO - 'tscrollx', 18:11:09 INFO - 'cart']}, 18:11:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'talos_options': ['--disable-e10s'], 18:11:09 INFO - 'tests': ['tp5o']}, 18:11:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'tests': ['tp5o']}, 18:11:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'talos_options': ['--disable-e10s', 18:11:09 INFO - '--xperf_path', 18:11:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:09 INFO - 'tests': ['tp5n']}, 18:11:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:09 INFO - 'talos_options': ['--xperf_path', 18:11:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:09 INFO - 'tests': ['tp5n']}}, 18:11:09 INFO - 'talos.zip': {'path': '', 18:11:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:11:09 INFO - Running post-action listener: _resource_record_post_action 18:11:09 INFO - [mozharness: 2017-01-03 02:11:09.796154Z] Finished populate-webroot step (success) 18:11:09 INFO - [mozharness: 2017-01-03 02:11:09.796283Z] Running create-virtualenv step. 18:11:09 INFO - Running pre-action listener: _resource_record_pre_action 18:11:09 INFO - Running main action method: create_virtualenv 18:11:09 INFO - Creating virtualenv /builds/slave/test/build/venv 18:11:09 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 18:11:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:11:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:11:09 INFO - Using real prefix '/tools/python27' 18:11:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:11:10 INFO - Installing distribute.............................................................................................................................................................................................done. 18:11:13 INFO - Installing pip.................done. 18:11:13 INFO - Return code: 0 18:11:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:11:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:11:13 INFO - Reading from file tmpfile_stdout 18:11:13 INFO - Output received: 18:11:13 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) 18:11:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:13 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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:13 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 18:11:13 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 18:11:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:13 INFO - 'HOME': '/Users/cltbld', 18:11:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:13 INFO - 'LOGNAME': 'cltbld', 18:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:13 INFO - 'MOZ_NO_REMOTE': '1', 18:11:13 INFO - 'NO_EM_RESTART': '1', 18:11:13 INFO - 'PAGER': '/bin/cat', 18:11:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:13 INFO - 'PWD': '/builds/slave/test', 18:11:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:13 INFO - 'SHELL': '/bin/bash', 18:11:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:13 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:13 INFO - 'USER': 'cltbld', 18:11:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:13 INFO - 'XPC_FLAGS': '0x0', 18:11:13 INFO - 'XPC_SERVICE_NAME': '0', 18:11:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:14 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 18:11:14 INFO - Cleaning up... 18:11:14 INFO - Return code: 0 18:11:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:11: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')]} 18:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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')]} 18:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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', '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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:11: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 psutil>=3.1.1 18:11:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:14 INFO - 'HOME': '/Users/cltbld', 18:11:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:14 INFO - 'LOGNAME': 'cltbld', 18:11:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:14 INFO - 'MOZ_NO_REMOTE': '1', 18:11:14 INFO - 'NO_EM_RESTART': '1', 18:11:14 INFO - 'PAGER': '/bin/cat', 18:11:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:14 INFO - 'PWD': '/builds/slave/test', 18:11:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:14 INFO - 'SHELL': '/bin/bash', 18:11:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:14 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:14 INFO - 'USER': 'cltbld', 18:11:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:14 INFO - 'XPC_FLAGS': '0x0', 18:11:14 INFO - 'XPC_SERVICE_NAME': '0', 18:11:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:14 INFO - Downloading/unpacking psutil>=3.1.1 18:11:14 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 18:11:14 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 18:11:14 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 18:11:14 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 18:11:14 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 18:11:14 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 18:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:11:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:18 INFO - Installing collected packages: psutil 18:11:18 INFO - Running setup.py install for psutil 18:11:18 INFO - building 'psutil._psutil_osx' extension 18:11:18 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 18:11:19 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 18:11:19 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 18:11:19 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 18:11:19 INFO - building 'psutil._psutil_posix' extension 18:11:19 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 18:11:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:11:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:11:19 INFO - ^ 18:11:19 INFO - 1 warning generated. 18:11:19 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 18:11:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:19 INFO - Successfully installed psutil 18:11:19 INFO - Cleaning up... 18:11:19 INFO - Return code: 0 18:11:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:11:19 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')]} 18:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:19 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')]} 18:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:19 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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:19 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 18:11:19 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 18:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:19 INFO - 'HOME': '/Users/cltbld', 18:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:19 INFO - 'LOGNAME': 'cltbld', 18:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:19 INFO - 'MOZ_NO_REMOTE': '1', 18:11:19 INFO - 'NO_EM_RESTART': '1', 18:11:19 INFO - 'PAGER': '/bin/cat', 18:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:19 INFO - 'PWD': '/builds/slave/test', 18:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:19 INFO - 'SHELL': '/bin/bash', 18:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:19 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:19 INFO - 'USER': 'cltbld', 18:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:19 INFO - 'XPC_FLAGS': '0x0', 18:11:19 INFO - 'XPC_SERVICE_NAME': '0', 18:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:11:19 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 18:11:19 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 18:11:19 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 18:11:19 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 18:11:19 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 18:11:19 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 18:11:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:11:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:11:22 INFO - Installing collected packages: mozsystemmonitor 18:11:22 INFO - Running setup.py install for mozsystemmonitor 18:11:22 INFO - Successfully installed mozsystemmonitor 18:11:22 INFO - Cleaning up... 18:11:22 INFO - Return code: 0 18:11:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:11:22 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')]} 18:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:22 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')]} 18:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:22 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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:22 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 18:11:22 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 18:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:22 INFO - 'HOME': '/Users/cltbld', 18:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:22 INFO - 'LOGNAME': 'cltbld', 18:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:22 INFO - 'MOZ_NO_REMOTE': '1', 18:11:22 INFO - 'NO_EM_RESTART': '1', 18:11:22 INFO - 'PAGER': '/bin/cat', 18:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:22 INFO - 'PWD': '/builds/slave/test', 18:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:22 INFO - 'SHELL': '/bin/bash', 18:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:22 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:22 INFO - 'USER': 'cltbld', 18:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:22 INFO - 'XPC_FLAGS': '0x0', 18:11:22 INFO - 'XPC_SERVICE_NAME': '0', 18:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:23 INFO - Downloading/unpacking jsonschema==2.5.1 18:11:23 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 18:11:23 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 18:11:23 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 18:11:23 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 18:11:23 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 18:11:23 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 18:11:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:11:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:11: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 18:11: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 18:11: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 18:11: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 18:11: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 18:11: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 18:11:26 INFO - Downloading functools32-3.2.3-2.tar.gz 18:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:11:26 INFO - warning: no files found matching '*.txt' 18:11:26 INFO - no previously-included directories found matching 'build' 18:11:26 INFO - no previously-included directories found matching 'dist' 18:11:26 INFO - no previously-included directories found matching '.git*' 18:11:26 INFO - Installing collected packages: jsonschema, functools32 18:11:26 INFO - Running setup.py install for functools32 18:11:26 INFO - warning: no files found matching '*.txt' 18:11:26 INFO - no previously-included directories found matching 'build' 18:11:26 INFO - no previously-included directories found matching 'dist' 18:11:26 INFO - no previously-included directories found matching '.git*' 18:11:26 INFO - Successfully installed jsonschema functools32 18:11:26 INFO - Cleaning up... 18:11:26 INFO - Return code: 0 18:11:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:11:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:26 INFO - 'HOME': '/Users/cltbld', 18:11:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:26 INFO - 'LOGNAME': 'cltbld', 18:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:26 INFO - 'MOZ_NO_REMOTE': '1', 18:11:26 INFO - 'NO_EM_RESTART': '1', 18:11:26 INFO - 'PAGER': '/bin/cat', 18:11:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:26 INFO - 'PWD': '/builds/slave/test', 18:11:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:26 INFO - 'SHELL': '/bin/bash', 18:11:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:26 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:26 INFO - 'USER': 'cltbld', 18:11:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:26 INFO - 'XPC_FLAGS': '0x0', 18:11:26 INFO - 'XPC_SERVICE_NAME': '0', 18:11:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:11:26 INFO - Cleaning up... 18:11:26 INFO - Return code: 0 18:11:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:11:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:11:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:26 INFO - 'HOME': '/Users/cltbld', 18:11:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:26 INFO - 'LOGNAME': 'cltbld', 18:11:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:26 INFO - 'MOZ_NO_REMOTE': '1', 18:11:26 INFO - 'NO_EM_RESTART': '1', 18:11:26 INFO - 'PAGER': '/bin/cat', 18:11:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:26 INFO - 'PWD': '/builds/slave/test', 18:11:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:26 INFO - 'SHELL': '/bin/bash', 18:11:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:26 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:26 INFO - 'USER': 'cltbld', 18:11:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:26 INFO - 'XPC_FLAGS': '0x0', 18:11:26 INFO - 'XPC_SERVICE_NAME': '0', 18:11:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:29 INFO - Downloading blobuploader-1.2.4.tar.gz 18:11:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:11:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:11:29 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 18:11:29 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 18:11:29 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 18:11:29 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 18:11:29 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 18:11:29 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 18:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:11:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:11:30 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 18:11:30 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 18:11:30 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 18:11:30 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 18:11:30 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 18:11:30 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 18:11:30 INFO - Downloading docopt-0.6.1.tar.gz 18:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:11:30 INFO - Installing collected packages: blobuploader, requests, docopt 18:11:30 INFO - Running setup.py install for blobuploader 18:11:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:11:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:11:31 INFO - Running setup.py install for requests 18:11:31 INFO - Running setup.py install for docopt 18:11:31 INFO - Successfully installed blobuploader requests docopt 18:11:31 INFO - Cleaning up... 18:11:31 INFO - Return code: 0 18:11:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11: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')]} 18:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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')]} 18:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:31 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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:31 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 18:11:31 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 18:11:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:31 INFO - 'HOME': '/Users/cltbld', 18:11:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:31 INFO - 'LOGNAME': 'cltbld', 18:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:31 INFO - 'MOZ_NO_REMOTE': '1', 18:11:31 INFO - 'NO_EM_RESTART': '1', 18:11:31 INFO - 'PAGER': '/bin/cat', 18:11:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:31 INFO - 'PWD': '/builds/slave/test', 18:11:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:31 INFO - 'SHELL': '/bin/bash', 18:11:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:31 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:31 INFO - 'USER': 'cltbld', 18:11:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:31 INFO - 'XPC_FLAGS': '0x0', 18:11:31 INFO - 'XPC_SERVICE_NAME': '0', 18:11:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-YgytLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-HP9Bxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-DY5aov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wI55qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-xBWf51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-RyFCy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-AnC5Hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-nEKoja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:32 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UFDvho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Fei1Ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-M2w2e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-E56eRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-kIa_JN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wS_5c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-UJa15X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-pva_NZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:33 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-DLfGLC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:11:33 INFO - Running setup.py install for manifestparser 18:11:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:11:34 INFO - Running setup.py install for mozcrash 18:11:34 INFO - Running setup.py install for mozdebug 18:11:34 INFO - Running setup.py install for mozdevice 18:11:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:11:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:11:34 INFO - Running setup.py install for mozfile 18:11:34 INFO - Running setup.py install for mozhttpd 18:11:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:11:34 INFO - Running setup.py install for mozinfo 18:11:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:11:34 INFO - Running setup.py install for mozInstall 18:11:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Running setup.py install for mozleak 18:11:35 INFO - Running setup.py install for mozlog 18:11:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Running setup.py install for moznetwork 18:11:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Running setup.py install for mozprocess 18:11:35 INFO - Running setup.py install for mozprofile 18:11:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:11:35 INFO - Running setup.py install for mozrunner 18:11:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:11:36 INFO - Running setup.py install for mozscreenshot 18:11:36 INFO - Running setup.py install for moztest 18:11:36 INFO - Running setup.py install for mozversion 18:11:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:11:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:11:36 INFO - Cleaning up... 18:11:36 INFO - Return code: 0 18:11:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11: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')]} 18:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11: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')]} 18:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:36 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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:36 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 18:11:36 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 18:11:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:36 INFO - 'HOME': '/Users/cltbld', 18:11:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:36 INFO - 'LOGNAME': 'cltbld', 18:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:36 INFO - 'MOZ_NO_REMOTE': '1', 18:11:36 INFO - 'NO_EM_RESTART': '1', 18:11:36 INFO - 'PAGER': '/bin/cat', 18:11:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:36 INFO - 'PWD': '/builds/slave/test', 18:11:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:36 INFO - 'SHELL': '/bin/bash', 18:11:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:36 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:36 INFO - 'USER': 'cltbld', 18:11:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:36 INFO - 'XPC_FLAGS': '0x0', 18:11:36 INFO - 'XPC_SERVICE_NAME': '0', 18:11:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-BmCrJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:36 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)) 18:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:36 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-vSdHJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-MSZLuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-PWbDRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-M2PQlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-xqTS6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Ea9YUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-sD2K15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-yF86uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-9R39sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:37 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)) 18:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:37 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Ezdf5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:38 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)) 18:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-3gLlDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:38 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)) 18:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-jKoTma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:38 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)) 18:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-f8BPJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:38 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)) 18:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-4TsE1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:38 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)) 18:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-cuhefz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-TfAkmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:38 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)) 18:11:38 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)) 18:11:38 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)) 18:11:38 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)) 18:11:38 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)) 18:11:38 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)) 18:11:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:41 INFO - Downloading blessings-1.6.tar.gz 18:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:11:41 INFO - Installing collected packages: blessings 18:11:41 INFO - Running setup.py install for blessings 18:11:41 INFO - Successfully installed blessings 18:11:41 INFO - Cleaning up... 18:11:41 INFO - Return code: 0 18:11:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:11:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:41 INFO - Reading from file tmpfile_stdout 18:11:41 INFO - Current package versions: 18:11:41 INFO - blessings == 1.6 18:11:41 INFO - blobuploader == 1.2.4 18:11:41 INFO - docopt == 0.6.1 18:11:41 INFO - functools32 == 3.2.3-2 18:11:41 INFO - jsonschema == 2.5.1 18:11:41 INFO - manifestparser == 1.1 18:11:41 INFO - mozInstall == 1.12 18:11:41 INFO - mozcrash == 1.0 18:11:41 INFO - mozdebug == 0.1 18:11:41 INFO - mozdevice == 0.48 18:11:41 INFO - mozfile == 1.2 18:11:41 INFO - mozhttpd == 0.7 18:11:41 INFO - mozinfo == 0.9 18:11:41 INFO - mozleak == 0.1 18:11:41 INFO - mozlog == 3.4 18:11:41 INFO - moznetwork == 0.27 18:11:41 INFO - mozprocess == 0.23 18:11:41 INFO - mozprofile == 0.28 18:11:41 INFO - mozrunner == 6.13 18:11:41 INFO - mozscreenshot == 0.1 18:11:41 INFO - mozsystemmonitor == 0.3 18:11:41 INFO - moztest == 0.7 18:11:41 INFO - mozversion == 1.4 18:11:41 INFO - psutil == 3.1.1 18:11:41 INFO - requests == 1.2.3 18:11:41 INFO - wsgiref == 0.1.2 18:11:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:41 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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:41 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 18:11:41 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 18:11:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:41 INFO - 'HOME': '/Users/cltbld', 18:11:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:41 INFO - 'LOGNAME': 'cltbld', 18:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:41 INFO - 'MOZ_NO_REMOTE': '1', 18:11:41 INFO - 'NO_EM_RESTART': '1', 18:11:41 INFO - 'PAGER': '/bin/cat', 18:11:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:41 INFO - 'PWD': '/builds/slave/test', 18:11:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:41 INFO - 'SHELL': '/bin/bash', 18:11:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:41 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:41 INFO - 'USER': 'cltbld', 18:11:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:41 INFO - 'XPC_FLAGS': '0x0', 18:11:41 INFO - 'XPC_SERVICE_NAME': '0', 18:11:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 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)) 18:11:42 INFO - Cleaning up... 18:11:42 INFO - Return code: 0 18:11:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:11:42 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')]} 18:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:42 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')]} 18:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:11:42 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 0x101cc6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101c3c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91b14e4fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, '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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 18:11:42 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 18:11:42 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 18:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:11:42 INFO - 'HOME': '/Users/cltbld', 18:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:11:42 INFO - 'LOGNAME': 'cltbld', 18:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:42 INFO - 'MOZ_NO_REMOTE': '1', 18:11:42 INFO - 'NO_EM_RESTART': '1', 18:11:42 INFO - 'PAGER': '/bin/cat', 18:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:42 INFO - 'PWD': '/builds/slave/test', 18:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:11:42 INFO - 'SHELL': '/bin/bash', 18:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:11:42 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:11:42 INFO - 'USER': 'cltbld', 18:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:42 INFO - 'XPC_FLAGS': '0x0', 18:11:42 INFO - 'XPC_SERVICE_NAME': '0', 18:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:11:42 INFO - Cleaning up... 18:11:42 INFO - Return code: 0 18:11:42 INFO - Running post-action listener: _resource_record_post_action 18:11:42 INFO - Running post-action listener: _start_resource_monitoring 18:11:42 INFO - Starting resource monitoring. 18:11:42 INFO - [mozharness: 2017-01-03 02:11:42.473907Z] Finished create-virtualenv step (success) 18:11:42 INFO - [mozharness: 2017-01-03 02:11:42.474875Z] Running install step. 18:11:42 INFO - Running pre-action listener: _resource_record_pre_action 18:11:42 INFO - Running main action method: install 18:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:42 INFO - Reading from file tmpfile_stdout 18:11:42 INFO - Detecting whether we're running mozinstall >=1.0... 18:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:11:42 INFO - Reading from file tmpfile_stdout 18:11:42 INFO - Output received: 18:11:42 INFO - Usage: mozinstall [options] installer 18:11:42 INFO - Options: 18:11:42 INFO - -h, --help show this help message and exit 18:11:42 INFO - -d DEST, --destination=DEST 18:11:42 INFO - Directory to install application into. [default: 18:11:42 INFO - "/builds/slave/test"] 18:11:42 INFO - --app=APP Application being installed. [default: firefox] 18:11:42 INFO - mkdir: /builds/slave/test/build/application 18:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:12:14 INFO - Reading from file tmpfile_stdout 18:12:14 INFO - Output received: 18:12:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:12:14 INFO - Running post-action listener: _resource_record_post_action 18:12:14 INFO - [mozharness: 2017-01-03 02:12:14.467741Z] Finished install step (success) 18:12:14 INFO - [mozharness: 2017-01-03 02:12:14.467907Z] Running run-tests step. 18:12:14 INFO - Running pre-action listener: _resource_record_pre_action 18:12:14 INFO - Running main action method: run_tests 18:12:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:12:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:12:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:12:14 INFO - 2017-01-02 18:12:14.482 screenresolution[1633:8415] starting screenresolution argv=screenresolution get 18:12:14 INFO - 2017-01-02 18:12:14.485 screenresolution[1633:8415] Display 0: 1600x1200x32@60 18:12:14 INFO - 2017-01-02 18:12:14.491 screenresolution[1634:8418] starting screenresolution argv=screenresolution list 18:12:14 INFO - Available Modes on Display 0 18:12:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:12:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:12:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:12:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:12:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:12:14 INFO - Intel Iris: 18:12:14 INFO - Chipset Model: Intel Iris 18:12:14 INFO - Type: GPU 18:12:14 INFO - Bus: Built-In 18:12:14 INFO - VRAM (Dynamic, Max): 1536 MB 18:12:14 INFO - Vendor: Intel (0x8086) 18:12:14 INFO - Device ID: 0x0a2e 18:12:14 INFO - Revision ID: 0x0009 18:12:14 INFO - Displays: 18:12:14 INFO - Display: 18:12:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:12:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:12:14 INFO - Display Serial Number: 1600x1200 60 18:12:14 INFO - Main Display: Yes 18:12:14 INFO - Mirror: Off 18:12:14 INFO - Online: Yes 18:12:14 INFO - Rotation: Supported 18:12:14 INFO - Return code: 0 18:12:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:14 INFO - Python 2.7.3 18:12:14 INFO - Return code: 0 18:12:14 INFO - grabbing minidump binary from tooltool 18:12: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')]} 18:12:14 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 0x101adad30>, '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 0x101940db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ad2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:14 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 18:12:14 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 18:12:14 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 18:12:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:14 INFO - Return code: 0 18:12:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:12:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:12:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:12:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fFxkQ3Nclf/Render', 18:12:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:12:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:12:14 INFO - 'HOME': '/Users/cltbld', 18:12:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:12:14 INFO - 'LOGNAME': 'cltbld', 18:12:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:14 INFO - 'MOZ_NO_REMOTE': '1', 18:12:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:14 INFO - 'NO_EM_RESTART': '1', 18:12:14 INFO - 'PAGER': '/bin/cat', 18:12:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:14 INFO - 'PWD': '/builds/slave/test', 18:12:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:12:14 INFO - 'SHELL': '/bin/bash', 18:12:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Axgf8QbgGK/Listeners', 18:12:14 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 18:12:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:12:14 INFO - 'USER': 'cltbld', 18:12:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:12:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:14 INFO - 'XPC_FLAGS': '0x0', 18:12:14 INFO - 'XPC_SERVICE_NAME': '0', 18:12:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:12:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:12:15 INFO - mozversion application_buildid: 20170102163320 18:12:15 INFO - mozversion application_changeset: 67635f34bbdcf21966af1c285724e8f5b1e64833 18:12:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:12:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:15 INFO - mozversion application_name: Firefox 18:12:15 INFO - mozversion application_remotingname: firefox-dev 18:12:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:12:15 INFO - mozversion application_vendor: Mozilla 18:12:15 INFO - mozversion application_version: 52.0a2 18:12:15 INFO - mozversion platform_buildid: 20170102163320 18:12:15 INFO - mozversion platform_changeset: 67635f34bbdcf21966af1c285724e8f5b1e64833 18:12:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:12:15 INFO - mozversion platform_version: 52.0a2 18:12:15 INFO - using testdate: 1483409535 18:12:15 INFO - actual date: 1483409535 18:12:15 INFO - starting webserver on 'localhost:49242' 18:12:15 INFO - SUITE-START | Running 2 tests 18:12:15 INFO - TEST-START | dromaeo_css 18:12:15 INFO - operating with platform_type : mac_ 18:12:15 INFO - Initialising browser for dromaeo_css test... 18:12:15 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpvZ4O9T/profile http://localhost:49242/getInfo.html) 18:12:17 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 18:12:17 INFO - PROCESS | 1644 | colorDepth:24 18:12:17 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 18:12:17 INFO - PROCESS | 1644 | __metrics 18:12:17 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:12:17 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:12:18 INFO - TEST-INFO | 1644: exit 0 18:12:18 INFO - Browser initialized. 18:12:18 INFO - Running cycle 1/1 for dromaeo_css test... 18:12:18 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpvZ4O9T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:30 INFO - PROCESS | 1647 | RSS: Main: 222842880 18:12:30 INFO - PROCESS | 1647 | 18:13:22 INFO - PROCESS | 1647 | 1483409602657 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:15:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 18:15:37 INFO - PROCESS | 1647 | RSS: Main: 390901760 18:15:37 INFO - PROCESS | 1647 | 18:18:40 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 18:18:41 INFO - PROCESS | 1647 | RSS: Main: 349257728 18:18:41 INFO - PROCESS | 1647 | 18:21:43 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 18:21:44 INFO - PROCESS | 1647 | RSS: Main: 338264064 18:21:44 INFO - PROCESS | 1647 | 18:24:46 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 18:24:47 INFO - PROCESS | 1647 | RSS: Main: 321671168 18:24:47 INFO - PROCESS | 1647 | 18:27:50 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 18:27:51 INFO - PROCESS | 1647 | RSS: Main: 312639488 18:27:51 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 18:30:54 INFO - PROCESS | 1647 | RSS: Main: 391831552 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | __start_tp_report 18:30:54 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 18:30:54 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 18:30:54 INFO - PROCESS | 1647 | |i|pagename|runs| 18:30:54 INFO - PROCESS | 1647 | |0;dojo.html;3339.432296509594;3347.995601319605;3366.2425954160303;3372.0221135870597;3426.1263377838645;5612.298462692163;5693.174489698991;5710.200571919934;5725.7995970141055;5748.683822389767;5654.830355089343;5749.511291540213;5755.338136114348;5763.9711801440935;5785.045467497858;5163.63854530183;5243.318368612075;5250.448702886209;5254.159334506479;5313.070212712779;5172.534471897528;5203.245529398235;5211.895762084756;5265.420803711589;5350.0904846176045;5249.081410753122;5256.185291279858;5278.498542638443;5293.232481290211;5294.311739473863;5269.1832765921345;5341.786328546854;5353.732313384316;5360.839174824722;5374.946250537484;5065.670731402447;5199.246109314128;5248.239005344242;5270.945810837848;5288.23320618512;5649.689267090308;5670.404607516192;5691.26013618227;5710.343310519302;5714.657120572738;6215.87568248637;6235.158253635746;6295.937040629581;6313.558050936436;6334.873302533923;6197.752089916443;6335.619862808247;6384.840378990516;6393.7442502299855;6399.968000159969;5323.920141197885;5780.50865676418;5790.9286781945475;5864.179014937912;5867;6308.161327402596;6325.810225693236;6335.334789847092;6356.300806911236;6358.046293056079;6031.4270144336215;6201.565890387629;6230.470410015108;6248.625082495065;6252.812415627538;6213.689315534296;6214.130021796952;6262.968685156545;6263.498920086382;6300.621962682162;6239.656818875005;6302.243730752339;6308.02225655015;6313.558050936482;6333;6299.968500157562;6300.527460440485;6336.493080553545;6337.271213810471;6354.205724284465;4854.2233242681705;4910.042541704404;4918.729469879119;4932.038252540792;4998.4251811042195;4446.155230506193;4463.196624607537;4469.888252793706;4495.775211239425;4497.8425755098415;5718.7426565804635;5718.79984200551;5727.971360143172;5729.455701708331;5735.053716136874;5160.303359046556;6281.434670879643;6282.780102696383;6289.0252010938375;6289.528285378615;5734.827955161351;5745.253117094751;5783.074708046776;5827.067669172912;5831.708414579254;5417.052015897202;5430.70131142791;5432.2123292123215;5445.700486473282;5465.125579907196;6396.848567257938;6405.583637063576;6408.807735767934;6419.839504012437;6426.710798014007;6144.723487442986;6185.319614842454;6230.470410015199;6238.75044998195;6355.713992870425;6369.936300636934;6370.840728981812;6371.808845734636;6375.234971803413;6385.425311721968;6245.531585131042;6246.5002799775;6252.499800015897;6268.467180289544;6270.372962703876;6144.7571636353305;6155.599886007395;6180.969095154495;6342.112104305309;6350.618962862243;4646.62826973848;6061.969690151521;6100.420460056288;6146.432659671934;6156.26124865019;6123.8142785821265;6125.693326622233;6127.525510585677;6131.957816599936;6142.682951206477;5905.586608937333;5993.101034844808;5994.220751302303;6039.365866584121;6047.334793172659;6097.878042439215;6112.047575716758;6117.082437634391;6129.481048574228;6139.6454669416935;5519.806806761744;5651.2935883014625;6021.126936594082;6024.3975602439405;6072.332043475126 18:30:54 INFO - PROCESS | 1647 | |1;ext.html;501.0680134948995;515.0728688360952;517.4179048570359;517.9145441002237;527.3197575128085;26538.73461265387;31347;32359.191020224447;32381.51427728586;32382.514262286084;32831.34337313252;33494.832525837395;33518.83240583794;33524.16189595255;42210;7967.60161991901;9138.680146194885;9303.395279306824;9313.441193528377;9329.766755831091;9185.586648600829;9273.582688778988;9274.907250927472;9300.813983720322;9304.395214311047;7972.1629228931;9257.953710231439;9276.953615231914;9303.348765586412;9317.534123293843;9174.724758257264;9229.723108306727;9240.445573265559;9240.95379523098;9253.16721495062;9217.953910230406;9297.395669281475;9301.441913485212;9306.069393060707;9315;40001.99995000119;40332.798336008425;40368.99077523056;40391.596084039076;40397.394039089435;3469.583649962021;3838.9059118151195;3856.936199930142;3895.3183192941124;3907.2380885727293;28706.425871482454;29467.70532294671;29622.25944351387;29724.86218164588;30560.8471957641;3162.3201011782576;3170.1282147409415;3175.0474857542645;3212.132724164486;3221.7100460958627;483714;492336;492954;493003.9999999964;494737.9999999964;62144.75710485855;62667.43331416751;62733.372666273666;62753;62796;34723;35558.644413555536;35561.82219088888;36543.81728091343;65631.67184164144;13764.242966636732;15800.36798528069;16031.038137711774;16062.518124456285;16070.035797852166;12178.391080445943;12290.20113692607;12296.631101066982;12321.075919306088;12330.321832299309;15761.921190393974;19195.904020480088;19284.710729339073;19294.32469863548;19323.4202973911;6292.874142517124;7427.25727427253;7558.13081495624;7565.43259255547;7624.771256862303;822.1737154160031;937.254882368505;951.9571619277149;994.5673631969971;1085.4301491716913;29095;29639.703602964124;30207.244818879703;30278.84860575727;30758;1058.8358804385332;1076.6231818863334;1089.6022951622601;1106.2919731371928;1111.77208672222;15030.774538381933;15494;15552.222388880511;15769.68460630781;15777.368905243891;681220;685332;688434;689833.5508322426;690638;58450.4154958445;58935.70532147312;59072.40927590755;59160.40839591549;59277.703611481666;1155.4569352404355;1203.2599951030054;1250.2186133666457;1252.273681264864;1378.0973462382156;29907.91565433925;31644.841775790974;32045.519317209793;32064.717411303285;33101;56162.7191864038;57336.42663573311;59609;59726;59796.70101649464;509133;607815;608525;608884;609996;35916.82041589775;36378.27243455169;43467;45271;45521.31718024166;42997.785011074746;46694.76652616851;47360;47502;47899;38075.80962095172;38791.6120838788;38865.222695546494;39530.802345988086;40195.1960960785;33095.834520827244;38806.64176753913;39372.60627393689;40810.9797255071;40834.9791255221;38089.66744655695;38140.23247094113;38402;38538.843834685;39016;32994.505082424264;34115.31769364649;34871.47692784661;34906.9527914163;35005.824970876005;21186;25939;26002.60996085098;26056.478870422863;26196.476070478104 18:30:54 INFO - PROCESS | 1647 | |2;jquery.html;314.90080624603246;343.1062083273075;370.39810308249093;370.57013863917854;401.2296390929415;14365.928170359146;14541.054831435962;15636.687266254683;16286.592835179126;20038.1984720611;16745.748813767805;19507.5123121922;19935.40193794185;20305.49236269094;20393.796062039375;5363.168708850135;5653.321601407837;5678.914816277738;5679.687617181054;5683.545316374701;5325.174597937326;5527.834164975057;5567.3019728982645;5591.664500129985;5597.916031259534;5523.199136125259;5523.420040895709;5524.696141712204;5528.419515950827;5532.889342213173;5064.56951159152;5292.92060619091;5345.503259087462;5504.4495550445035;5511.200875872996;4210.347396153601;4931.605471562267;4936.975315123437;4941.45643979162;4949.084419382426;24698.382540436454;24978.250652480274;25058.624120638204;25066.624000640004;25330;2265.7960783529397;2404.2923865728953;2465.8767061646845;2620.3711109333813;2674.558697814858;9522.190613797835;10042.397456152654;14421.278936053155;15012.849871501254;15865.048097114095;1735.8177391373977;1774.8402643761995;1794.5423916901236;1803.4048763907879;1813.954651133719;107429;110337;111056.4447177759;111822.99999999919;111856.4407177959;11210.439478026066;11267.718307042225;11301.604443844426;11367.488463019185;11460.828087578693;14265.288165420216;14273.000889937604;14283;14294.714105718036;14305.85694143066;6950.721971121098;8278.172182781673;8313.12712165226;8326.833463330699;8355.164483551596;8168.632411541495;8193.344532437392;8328.375371847014;8340.833183336421;8342.290905273;4299.376590394375;4403.823847046082;4413.22768515515;4446.888827779331;4529.886752831139;1556.2763315058326;1806.6296409236074;1869.1495369606796;1869.3363855831255;1907.523119220195;803.4536515169624;874.226309715906;914.0950459045497;961.5480724059681;983.188869182927;19997.400077997685;20357.89821050915;20392.694109588367;20447.795522044587;20490.59018819615;901.324006994754;906.4816432475709;925.8194652042922;944.244604316544;953.7567920180448;10042.447665378328;10516.316439431412;10613.310134841211;10680;10700.892991070144;570565.1471742615;581616;590770;593566.064339351;594233;86342.56828715817;96997.51501242448;115777.89554731257;119087;120579;902.9051949545335;913.3925939250457;953.3040880157524;957.4111921168432;958.4632605740808;17941.372051978116;18245.270189192284;18467.16897739578;18502.074896254864;18539.907300463412;48737.5126248733;49815.75092124516;49949.500504995936;50230.748846255534;50645.746771265905;67725.98411023937;72794.90807637783;73701;73839.26160738539;74684;7447.180810110775;7475.327220550176;7550.056242969629;7566.35685966677;7732.613369331399;6968.540278921158;11527.02020328248;11686.006689431493;12166.330851803235;12175.695607609881;10001.449920254456;10021.799564008825;10087;10178.88812164993;10365.533550990374;9967.900320996698;10019.348742331726;10027.799444010932;10254.384736915808;10265.332753371007;8440.83118337642;9997.500124993576;10241.617381653381;10253.33353332031;10488.31825931312;7496.362809161283;7635.961820190863;7658.387329013554;7698.153203147761;7792.649330780015;9400.623975040922;10275.280730348804;10441.399547409921;10481.056704896597;10725.30285531438 18:30:54 INFO - PROCESS | 1647 | |3;mootools.html;1451.2163431746853;1477.3204326010034;1620.4085508789287;1766.7668167253926;1822.4532640207935;8020.157883422245;8463.449875758071;8466.788330291745;8473.872891906627;8481.95759021204;7420.703171873132;7637.045369328834;7653.4259930505295;7681.923180768191;8240.50556966581;2273.0794028418477;2310.1452462588863;2314.9537009259807;2317;2325.3256555598855;2296.276672848056;2329.301209637111;2338.4153961509624;2338.4504641409253;2349.177787774274;2284.2690339091414;2290.7823756743082;2307.227078928561;2314.2363020203375;2320.8491448055906;2297.873616951067;2304.331743794298;2310.0644239083113;2318.5362927414585;2319.0028287836226;2301.3326135420707;2307.5846347657416;2324.3491822289784;2333.101755824003;2333.7666233376694;8004.839903201903;8031.397645176577;8033.95983020087;8039.839203215962;8070.838583228303;4964.851054468371;5179.326687530634;5187.429382767892;5203.635745497817;5208;3091.2426455518316;3183.3792410479964;3228.9192770180935;3456.654334566548;3517.2437925846066;995.8804943406765;1049.5329578337644;1051.5846240734943;1069.7913906788178;1087.7770057138268;10968.067714244298;11006.449677516172;11008.559657613685;11034.448277586169;11048.06091482225;4513.187626227311;4611.584957353854;4690;4746.501617330199;4759.214729569582;9394.812103758024;9397.859032114524;9407.529623518796;9417.717468475958;9453.810923781486;5169.534741873174;5255.47445255479;5256.868578285497;5259.132243179833;5268.6575372600655;3973.523177218752;4493.910121797611;4699.530046995342;4745.857624271277;4794.472608013116;2808.6208361870886;3153.274746808201;3614.367485689994;3799.525059367579;3807.0672685192303;1229.9631011069682;1233.210745123122;1241.8229285579573;1249.1068885746736;1251;789.2226157235119;892.9464232146092;897.3718397122041;903.3992395057213;916.9403988740711;5502.257195278667;5587.972060139673;5633.295838020247;5639.689817060019;5846.1523079153285;434.7282948157402;438.3030980740574;442.4253221687764;448.3924282597099;451.26669162610756;3781.7541859779026;3816.3321418751607;3832.9041773955873;3851.7496362736333;3902.2195560887935;9797.06927841854;10103.84844227337;10229.948850255701;10235.846462303072;10246.897531024744;10182.592696292066;10189.796204075878;10198.929112443087;10199.235057370579;10201.795964080677;7559.20628334028;7749.690012399441;7829.882551761728;7832.216778322236;7899.368050555941;5797.507211887037;5805.187273781589;5874.706264686834;5924.022536281465;5953.76184952597;9016.2786977043;9300.72097837066;9415.19970802462;9463.763405914908;9471.952640236756;8722.258608018135;9480.52597370115;9586.28102892272;9617.615295388106;9646.469444180637;6742.359475849787;6815.965920170367;6818.454523638196;6901.792946211421;7185.2814718527725;6655.467562595078;7884.84230315379;7901.525908445512;7969.123396426505;8006.039275287003;7172.9641351795;7190.7483238086415;7200.71197152108;7209.459290553124;7237.8914316284245;6979.40675042627;7019.613921234177;7046.541974771623;7126.5011449198055;7159.176694680178;6594.538382313192;6633.3035031321015;6711.798646040626;6719.496037797087;6724.630145341857;4756.500567440507;4848.10310092634;4852.053849499313;4883.5116488352305;4968.354113965162;4530.705504142352;4541.863744087683;4554.27131658933;4688.578027977362;4744.074905393415 18:30:54 INFO - PROCESS | 1647 | |4;prototype.html;274.7046924556102;300.1011969152389;304.28341256341315;308.1448979083044;312.3783670495711;4249.830006799728;4255.935871102521;4984.651074424787;5112.386513618371;5113.514216149464;3959.7624142551467;4095.8771236862867;4805.639577031728;4891.926621100677;5043.344365232515;3738.9813050934845;3936.3701807710772;3937.0551067743677;3955.6835453163676;3956.3669812829953;3932.488776459056;3946.3488524393442;3956.4263181838614;3970.4838371011874;3974.662153716923;3403.6596340366013;3645.2891686121216;3668.19299754055;3912.8261521543564;3951.6443520083185;3868.574456809749;3880.341965753468;3885.2812229737447;3889.2027134437653;3903.843846246161;3005.2637103909683;3357.983210083934;3360.4959256111533;3385.64450732672;3419.6238413774463;4093.0557984957204;4458.019235768126;4573.794179261941;4932.334134891816;4980.228064649985;3721.6464435878547;4143.958560414418;4322.897661096432;4437.378766972626;4489.528599497038;4639.559241872017;5290.920636190422;5333.946430624238;5385.353757549119;5395.973020134874;3658.396364599838;3679.7608155469807;3682.5143028455686;3699.4635777812337;3724.2923844469465;5360.490753378423;5518.751656175482;5538.483063254782;5541.584381171388;5608.523275521586;3756.760269111187;3769.2838360711926;3772.7170462215445;3776.263628592398;3777.376732839106;3770.472133901256;3779.4708740776314;3782.2813665403487;3799.2781371539318;3805.238952209569;4182.728122672017;4388.4733831940375;4405.713628614129;4416.293393057095;4428.4685837699035;4676.906461870743;4947.282644016597;5368.892622147535;5390.574241591221;5437.238786569884;1014.3964341216964;1058.4548957287004;1215.769003889258;1228.7419641875306;1260.8108783682521;684.7021545627667;813.7965508622844;842.8145807922248;843.401185158532;845.6997765793178;4330.453024324447;4359.476862776487;4503.392042074278;4648.814047438064;4864.489228631013;5183.248428977777;5228.215767634885;5230.738463076831;5251;5324.524096295164;3975.940360894589;4215.473065866767;4705.529447055267;4776.8566942991765;5143.305653736773;2441.827922597128;2538.860362680033;2754.449110177972;2797.846118463504;2810.718928107173;5201.902135906833;5281.313429254249;5297.337832770903;5333.493318134771;5366.275552800322;5244.580433565306;5324.547413469821;5696.031674615326;5715.228444159985;5795.681237531892;5684.971575142181;5685.886282274414;5688.77244910199;5690.715464226772;5711.230053424003;5395.757190926496;5456.574555079262;5551.417101204477;5551.417845913895;5552.194931734956;4551.40831691878;5437.564994800327;5447.509724124848;5463.617546771688;5550.750216240198;4673.102676340554;5079.390473143247;5715.799682066646;5732.59231122921;5755.625884317506;3262.722668573198;3434.9656503434644;3783.6027217142905;3892.6885849131436;3953;5454.890902182014;5456.263404440509;5486.506214440719;5510.283663123768;5545.918545883514;5132.610758703421;5314.760835762323;5333.599980001438;5334.226537152169;5359.650218613367;5126.871828204325;5140;5155.690658560498;5212.54048866318;5335.653182543122;5152.793888244429;5239.580833533384;5248.448912864094;5269.789208431773;5272.393674727302;4170.624643782075;4979.502049794992;4981.302617633607;5248.582882621748;5373.650712703662;4758.52485729425;4811.230203167476;4954.8761280968265;5226.66719986401;5240.187770895517 18:30:54 INFO - PROCESS | 1647 | |5;yui.html;663.8406782372232;696.578569965171;707.0560801330221;731.8499707559951;762.8474305138972;13889.444422223112;14346;14346.713065738679;14381.424743010268;14385;12840.165389249692;12867.806982895265;12917.935410322958;12976.93511532441;13015.219086854771;630.7792272704539;650.7462089784988;652.7193306878064;787.4251796188786;796.1958421993785;814.2997022560593;819.7212947597816;826.4132465949183;828.0684230240979;831.1397703377006;872.9127087291283;876.1895246896595;877.8814859993915;879.4459490520962;880.5641207602246;670.6680193304297;692.0864458914235;874.1433395272637;874.6020560644891;874.8687696845492;620.8913440147955;637.5314144104126;639.6290151712043;641.3458272562008;642.8103709405736;13858.722825543533;13907.513237036655;13909.026368154233;13914.93042534791;13936.442542298297;12739.108262421634;12926.482940682361;13111.868881311255;13113.409896554582;13122.2126672399;10759.193060520493;11050.005499505083;11135.164862635254;11478;11687.50093641845;4298.978505107454;4506.211413002744;4508;4609.03210325832;4631.8147274109;66657.66671166662;83437.3312533752;85516.57241713752;85701.28597428079;87342.56328718379;9942.552585133686;10182.490875456198;10367.222458315657;10483.947580262049;10558.366498009991;19185.424437266905;19679.803201968083;19961.800381995996;20687.8965605174;20703;7417.554946703215;7496.25037496246;7677.769666910001;7836.216378362232;7838.255365740246;3635.9092272318726;3874.2638898609175;3956.6043395660204;4239.618434340924;4292.784267115469;3381.526586277923;3513.631068737797;3514.7012503937453;3515.8593656253465;3517.982410087933;638.8977763557901;652.5921299188008;663.1048085085097;667.9599224046573;670.0987172253311;564.1960206705428;577.4773829684143;626.3861415812529;647.414090248326;656.2158220925947;7906;8497.532635705096;8561.529115898686;8700.390972631854;9026.684066057656;552.6407834907343;658.6443320606915;659.2846761263986;662.059874977533;667.5927684112687;4084.8766589187617;4265.76538290397;4458.843940462133;4466.6203372713035;4493.348464472699;90469;96786.51606741922;110042.44978775215;110245.89754102517;119760;92488.07511924929;95167.04832951582;95684;96463;97398.51300743593;90488.54755726179;92467.5376623126;92641.5367923156;93040;95128.9140423058;8511.914880851113;8523.573821308786;8710.21608055278;8737.995130560066;8753;48449.5155048445;57935.1309730346;58190.70904645619;59068;61394.77210455855;80012;82907.5854620723;84381;85310.14689853022;87630;10975.890241097806;11073.003429691367;11084.224104312621;11140.610078647533;11464.968152866282;10419.53112109972;11252.324860508395;11528.07775377951;11602.94198529002;11970.042396608425;11916.642500724993;11974.880251197725;12226.510939562673;12488;12507.874921251036;11839.408029598659;11987.76024479523;12457.941075008726;12482.375881206084;12547.811782823448;11869.762604747684;11934.582289619822;12352.814707779211;12391.256524608552;12418.006559475401;9497.14525692691;9659.565319560777;9944.403335799876;10027;10297.279190456597;8422.284105850826;10073.395596264247;10148.492575371054;10151.89848101539;10157.746056348355 18:30:54 INFO - PROCESS | 1647 | __end_tp_report 18:30:54 INFO - PROCESS | 1647 | __start_cc_report 18:30:54 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1989 18:30:54 INFO - PROCESS | 1647 | __end_cc_report 18:30:54 INFO - PROCESS | 1647 | __startTimestamp1483410654731__endTimestamp 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | ------- Summary: start ------- 18:30:54 INFO - PROCESS | 1647 | Number of tests: 6 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5769.85 Median:6021.13 stddev:653.91 (10.9%) stddev-sans-first:627.50 18:30:54 INFO - PROCESS | 1647 | Values: 3339.4 3348.0 3366.2 3372.0 3426.1 5612.3 5693.2 5710.2 5725.8 5748.7 5654.8 5749.5 5755.3 5764.0 5785.0 5163.6 5243.3 5250.4 5254.2 5313.1 5172.5 5203.2 5211.9 5265.4 5350.1 5249.1 5256.2 5278.5 5293.2 5294.3 5269.2 5341.8 5353.7 5360.8 5374.9 5065.7 5199.2 5248.2 5270.9 5288.2 5649.7 5670.4 5691.3 5710.3 5714.7 6215.9 6235.2 6295.9 6313.6 6334.9 6197.8 6335.6 6384.8 6393.7 6400.0 5323.9 5780.5 5790.9 5864.2 5867.0 6308.2 6325.8 6335.3 6356.3 6358.0 6031.4 6201.6 6230.5 6248.6 6252.8 6213.7 6214.1 6263.0 6263.5 6300.6 6239.7 6302.2 6308.0 6313.6 6333.0 6300.0 6300.5 6336.5 6337.3 6354.2 4854.2 4910.0 4918.7 4932.0 4998.4 4446.2 4463.2 4469.9 4495.8 4497.8 5718.7 5718.8 5728.0 5729.5 5735.1 5160.3 6281.4 6282.8 6289.0 6289.5 5734.8 5745.3 5783.1 5827.1 5831.7 5417.1 5430.7 5432.2 5445.7 5465.1 6396.8 6405.6 6408.8 6419.8 6426.7 6144.7 6185.3 6230.5 6238.8 6355.7 6369.9 6370.8 6371.8 6375.2 6385.4 6245.5 6246.5 6252.5 6268.5 6270.4 6144.8 6155.6 6181.0 6342.1 6350.6 4646.6 6062.0 6100.4 6146.4 6156.3 6123.8 6125.7 6127.5 6132.0 6142.7 5905.6 5993.1 5994.2 6039.4 6047.3 6097.9 6112.0 6117.1 6129.5 6139.6 5519.8 5651.3 6021.1 6024.4 6072.3 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:71556.16 Median:21639.92 stddev:159309.93 (736.2%) stddev-sans-first:159667.51 18:30:54 INFO - PROCESS | 1647 | Values: 501.1 515.1 517.4 517.9 527.3 26538.7 31347.0 32359.2 32381.5 32382.5 32831.3 33494.8 33518.8 33524.2 42210.0 7967.6 9138.7 9303.4 9313.4 9329.8 9185.6 9273.6 9274.9 9300.8 9304.4 7972.2 9258.0 9277.0 9303.3 9317.5 9174.7 9229.7 9240.4 9241.0 9253.2 9218.0 9297.4 9301.4 9306.1 9315.0 40002.0 40332.8 40369.0 40391.6 40397.4 3469.6 3838.9 3856.9 3895.3 3907.2 28706.4 29467.7 29622.3 29724.9 30560.8 3162.3 3170.1 3175.0 3212.1 3221.7 483714.0 492336.0 492954.0 493004.0 494738.0 62144.8 62667.4 62733.4 62753.0 62796.0 34723.0 35558.6 35561.8 36543.8 65631.7 13764.2 15800.4 16031.0 16062.5 16070.0 12178.4 12290.2 12296.6 12321.1 12330.3 15761.9 19195.9 19284.7 19294.3 19323.4 6292.9 7427.3 7558.1 7565.4 7624.8 822.2 937.3 952.0 994.6 1085.4 29095.0 29639.7 30207.2 30278.8 30758.0 1058.8 1076.6 1089.6 1106.3 1111.8 15030.8 15494.0 15552.2 15769.7 15777.4 681220.0 685332.0 688434.0 689833.6 690638.0 58450.4 58935.7 59072.4 59160.4 59277.7 1155.5 1203.3 1250.2 1252.3 1378.1 29907.9 31644.8 32045.5 32064.7 33101.0 56162.7 57336.4 59609.0 59726.0 59796.7 509133.0 607815.0 608525.0 608884.0 609996.0 35916.8 36378.3 43467.0 45271.0 45521.3 42997.8 46694.8 47360.0 47502.0 47899.0 38075.8 38791.6 38865.2 39530.8 40195.2 33095.8 38806.6 39372.6 40811.0 40835.0 38089.7 38140.2 38402.0 38538.8 39016.0 32994.5 34115.3 34871.5 34907.0 35005.8 21186.0 25939.0 26002.6 26056.5 26196.5 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:33289.36 Median:385.90 stddev:97328.00 (25221.0%) stddev-sans-first:97569.54 18:30:54 INFO - PROCESS | 1647 | Values: 314.9 343.1 370.4 370.6 401.2 14365.9 14541.1 15636.7 16286.6 20038.2 16745.7 19507.5 19935.4 20305.5 20393.8 5363.2 5653.3 5678.9 5679.7 5683.5 5325.2 5527.8 5567.3 5591.7 5597.9 5523.2 5523.4 5524.7 5528.4 5532.9 5064.6 5292.9 5345.5 5504.4 5511.2 4210.3 4931.6 4937.0 4941.5 4949.1 24698.4 24978.3 25058.6 25066.6 25330.0 2265.8 2404.3 2465.9 2620.4 2674.6 9522.2 10042.4 14421.3 15012.8 15865.0 1735.8 1774.8 1794.5 1803.4 1814.0 107429.0 110337.0 111056.4 111823.0 111856.4 11210.4 11267.7 11301.6 11367.5 11460.8 14265.3 14273.0 14283.0 14294.7 14305.9 6950.7 8278.2 8313.1 8326.8 8355.2 8168.6 8193.3 8328.4 8340.8 8342.3 4299.4 4403.8 4413.2 4446.9 4529.9 1556.3 1806.6 1869.1 1869.3 1907.5 803.5 874.2 914.1 961.5 983.2 19997.4 20357.9 20392.7 20447.8 20490.6 901.3 906.5 925.8 944.2 953.8 10042.4 10516.3 10613.3 10680.0 10700.9 570565.1 581616.0 590770.0 593566.1 594233.0 86342.6 96997.5 115777.9 119087.0 120579.0 902.9 913.4 953.3 957.4 958.5 17941.4 18245.3 18467.2 18502.1 18539.9 48737.5 49815.8 49949.5 50230.7 50645.7 67726.0 72794.9 73701.0 73839.3 74684.0 7447.2 7475.3 7550.1 7566.4 7732.6 6968.5 11527.0 11686.0 12166.3 12175.7 10001.4 10021.8 10087.0 10178.9 10365.5 9967.9 10019.3 10027.8 10254.4 10265.3 8440.8 9997.5 10241.6 10253.3 10488.3 7496.4 7636.0 7658.4 7698.2 7792.6 9400.6 10275.3 10441.4 10481.1 10725.3 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5413.74 Median:4867.78 stddev:3007.13 (61.8%) stddev-sans-first:3000.82 18:30:54 INFO - PROCESS | 1647 | Values: 1451.2 1477.3 1620.4 1766.8 1822.5 8020.2 8463.4 8466.8 8473.9 8482.0 7420.7 7637.0 7653.4 7681.9 8240.5 2273.1 2310.1 2315.0 2317.0 2325.3 2296.3 2329.3 2338.4 2338.5 2349.2 2284.3 2290.8 2307.2 2314.2 2320.8 2297.9 2304.3 2310.1 2318.5 2319.0 2301.3 2307.6 2324.3 2333.1 2333.8 8004.8 8031.4 8034.0 8039.8 8070.8 4964.9 5179.3 5187.4 5203.6 5208.0 3091.2 3183.4 3228.9 3456.7 3517.2 995.9 1049.5 1051.6 1069.8 1087.8 10968.1 11006.4 11008.6 11034.4 11048.1 4513.2 4611.6 4690.0 4746.5 4759.2 9394.8 9397.9 9407.5 9417.7 9453.8 5169.5 5255.5 5256.9 5259.1 5268.7 3973.5 4493.9 4699.5 4745.9 4794.5 2808.6 3153.3 3614.4 3799.5 3807.1 1230.0 1233.2 1241.8 1249.1 1251.0 789.2 892.9 897.4 903.4 916.9 5502.3 5588.0 5633.3 5639.7 5846.2 434.7 438.3 442.4 448.4 451.3 3781.8 3816.3 3832.9 3851.7 3902.2 9797.1 10103.8 10229.9 10235.8 10246.9 10182.6 10189.8 10198.9 10199.2 10201.8 7559.2 7749.7 7829.9 7832.2 7899.4 5797.5 5805.2 5874.7 5924.0 5953.8 9016.3 9300.7 9415.2 9463.8 9472.0 8722.3 9480.5 9586.3 9617.6 9646.5 6742.4 6816.0 6818.5 6901.8 7185.3 6655.5 7884.8 7901.5 7969.1 8006.0 7173.0 7190.7 7200.7 7209.5 7237.9 6979.4 7019.6 7046.5 7126.5 7159.2 6594.5 6633.3 6711.8 6719.5 6724.6 4756.5 4848.1 4852.1 4883.5 4968.4 4530.7 4541.9 4554.3 4688.6 4744.1 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4312.10 Median:4791.25 stddev:1328.05 (27.7%) stddev-sans-first:1296.75 18:30:54 INFO - PROCESS | 1647 | Values: 274.7 300.1 304.3 308.1 312.4 4249.8 4255.9 4984.7 5112.4 5113.5 3959.8 4095.9 4805.6 4891.9 5043.3 3739.0 3936.4 3937.1 3955.7 3956.4 3932.5 3946.3 3956.4 3970.5 3974.7 3403.7 3645.3 3668.2 3912.8 3951.6 3868.6 3880.3 3885.3 3889.2 3903.8 3005.3 3358.0 3360.5 3385.6 3419.6 4093.1 4458.0 4573.8 4932.3 4980.2 3721.6 4144.0 4322.9 4437.4 4489.5 4639.6 5290.9 5333.9 5385.4 5396.0 3658.4 3679.8 3682.5 3699.5 3724.3 5360.5 5518.8 5538.5 5541.6 5608.5 3756.8 3769.3 3772.7 3776.3 3777.4 3770.5 3779.5 3782.3 3799.3 3805.2 4182.7 4388.5 4405.7 4416.3 4428.5 4676.9 4947.3 5368.9 5390.6 5437.2 1014.4 1058.5 1215.8 1228.7 1260.8 684.7 813.8 842.8 843.4 845.7 4330.5 4359.5 4503.4 4648.8 4864.5 5183.2 5228.2 5230.7 5251.0 5324.5 3975.9 4215.5 4705.5 4776.9 5143.3 2441.8 2538.9 2754.4 2797.8 2810.7 5201.9 5281.3 5297.3 5333.5 5366.3 5244.6 5324.5 5696.0 5715.2 5795.7 5685.0 5685.9 5688.8 5690.7 5711.2 5395.8 5456.6 5551.4 5551.4 5552.2 4551.4 5437.6 5447.5 5463.6 5550.8 4673.1 5079.4 5715.8 5732.6 5755.6 3262.7 3435.0 3783.6 3892.7 3953.0 5454.9 5456.3 5486.5 5510.3 5545.9 5132.6 5314.8 5333.6 5334.2 5359.7 5126.9 5140.0 5155.7 5212.5 5335.7 5152.8 5239.6 5248.4 5269.8 5272.4 4170.6 4979.5 4981.3 5248.6 5373.7 4758.5 4811.2 4954.9 5226.7 5240.2 18:30:54 INFO - PROCESS | 1647 | 18:30:54 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20517.45 Median:570.84 stddev:30551.20 (5352.0%) stddev-sans-first:30600.53 18:30:54 INFO - PROCESS | 1647 | Values: 663.8 696.6 707.1 731.8 762.8 13889.4 14346.0 14346.7 14381.4 14385.0 12840.2 12867.8 12917.9 12976.9 13015.2 630.8 650.7 652.7 787.4 796.2 814.3 819.7 826.4 828.1 831.1 872.9 876.2 877.9 879.4 880.6 670.7 692.1 874.1 874.6 874.9 620.9 637.5 639.6 641.3 642.8 13858.7 13907.5 13909.0 13914.9 13936.4 12739.1 12926.5 13111.9 13113.4 13122.2 10759.2 11050.0 11135.2 11478.0 11687.5 4299.0 4506.2 4508.0 4609.0 4631.8 66657.7 83437.3 85516.6 85701.3 87342.6 9942.6 10182.5 10367.2 10483.9 10558.4 19185.4 19679.8 19961.8 20687.9 20703.0 7417.6 7496.3 7677.8 7836.2 7838.3 3635.9 3874.3 3956.6 4239.6 4292.8 3381.5 3513.6 3514.7 3515.9 3518.0 638.9 652.6 663.1 668.0 670.1 564.2 577.5 626.4 647.4 656.2 7906.0 8497.5 8561.5 8700.4 9026.7 552.6 658.6 659.3 662.1 667.6 4084.9 4265.8 4458.8 4466.6 4493.3 90469.0 96786.5 110042.4 110245.9 119760.0 92488.1 95167.0 95684.0 96463.0 97398.5 90488.5 92467.5 92641.5 93040.0 95128.9 8511.9 8523.6 8710.2 8738.0 8753.0 48449.5 57935.1 58190.7 59068.0 61394.8 80012.0 82907.6 84381.0 85310.1 87630.0 10975.9 11073.0 11084.2 11140.6 11465.0 10419.5 11252.3 11528.1 11602.9 11970.0 11916.6 11974.9 12226.5 12488.0 12507.9 11839.4 11987.8 12457.9 12482.4 12547.8 11869.8 11934.6 12352.8 12391.3 12418.0 9497.1 9659.6 9944.4 10027.0 10297.3 8422.3 10073.4 10148.5 10151.9 10157.7 18:30:54 INFO - PROCESS | 1647 | -------- Summary: end -------- 18:30:54 INFO - PROCESS | 1647 | 18:30:55 INFO - TEST-INFO | 1647: exit 0 18:30:55 INFO - TEST-OK | dromaeo_css | took 1120345ms 18:30:55 INFO - TEST-START | kraken 18:30:55 INFO - operating with platform_type : mac_ 18:30:55 INFO - Initialising browser for kraken test... 18:30:55 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp9rB7NF/profile http://localhost:49242/getInfo.html) 18:30:57 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 18:30:57 INFO - PROCESS | 1655 | colorDepth:24 18:30:57 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 18:30:57 INFO - PROCESS | 1655 | __metrics 18:30:57 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:30:57 INFO - PROCESS | 1655 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:30:58 INFO - TEST-INFO | 1655: exit 0 18:30:58 INFO - Browser initialized. 18:30:58 INFO - Running cycle 1/1 for kraken test... 18:30:58 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp9rB7NF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:10 INFO - PROCESS | 1656 | RSS: Main: 222789632 18:31:10 INFO - PROCESS | 1656 | 18:31:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 18:31:14 INFO - PROCESS | 1656 | RSS: Main: 272904192 18:31:14 INFO - PROCESS | 1656 | 18:31:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 18:31:17 INFO - PROCESS | 1656 | RSS: Main: 283533312 18:31:17 INFO - PROCESS | 1656 | 18:31:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 18:31:21 INFO - PROCESS | 1656 | RSS: Main: 381317120 18:31:21 INFO - PROCESS | 1656 | 18:31:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 18:31:24 INFO - PROCESS | 1656 | RSS: Main: 284192768 18:31:24 INFO - PROCESS | 1656 | 18:31:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:27 INFO - PROCESS | 1656 | RSS: Main: 285278208 18:31:27 INFO - PROCESS | 1656 | 18:31:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 18:31:30 INFO - PROCESS | 1656 | RSS: Main: 326828032 18:31:30 INFO - PROCESS | 1656 | 18:31:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 18:31:34 INFO - PROCESS | 1656 | RSS: Main: 327016448 18:31:34 INFO - PROCESS | 1656 | 18:31:36 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 18:31:37 INFO - PROCESS | 1656 | RSS: Main: 322990080 18:31:37 INFO - PROCESS | 1656 | 18:31:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:31:39 INFO - PROCESS | 1656 | RSS: Main: 300924928 18:31:39 INFO - PROCESS | 1656 | 18:31:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:31:42 INFO - PROCESS | 1656 | RSS: Main: 301215744 18:31:42 INFO - PROCESS | 1656 | 18:31:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:31:44 INFO - PROCESS | 1656 | RSS: Main: 303988736 18:31:44 INFO - PROCESS | 1656 | 18:31:47 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:31:48 INFO - PROCESS | 1656 | RSS: Main: 319827968 18:31:48 INFO - PROCESS | 1656 | 18:31:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:31:51 INFO - PROCESS | 1656 | RSS: Main: 313737216 18:31:51 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 18:31:53 INFO - PROCESS | 1656 | RSS: Main: 357687296 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | __start_tp_report 18:31:53 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 18:31:53 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 18:31:53 INFO - PROCESS | 1656 | |i|pagename|runs| 18:31:53 INFO - PROCESS | 1656 | |0;ai-astar;116;114;114;114;113;114;114;113;114;112 18:31:53 INFO - PROCESS | 1656 | |1;audio-beat-detection;149;110;112;146;108;104;133;113;140;110 18:31:53 INFO - PROCESS | 1656 | |2;audio-dft;183;185;182;185;185;184;184;184;211;264 18:31:53 INFO - PROCESS | 1656 | |3;audio-fft;76;62;81;115;51;73;75;83;112;71 18:31:53 INFO - PROCESS | 1656 | |4;audio-oscillator;88;71;95;69;69;70;89;67;69;93 18:31:53 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;87;90;87;89;86;90;87;89;87 18:31:53 INFO - PROCESS | 1656 | |6;imaging-darkroom;95;95;95;95;93;96;96;96;94;104 18:31:53 INFO - PROCESS | 1656 | |7;imaging-desaturate;81;83;80;80;81;81;89;84;81;84 18:31:53 INFO - PROCESS | 1656 | |8;json-parse-financial;55;52;53;54;58;58;57;57;58;57 18:31:53 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;65;72;71;74;72;72;68;70;70;70 18:31:53 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;85;84;84;85;83;83;84;84;87;84 18:31:53 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;142;139;137;139;138;145;145;162;186;138 18:31:53 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;165;168;173;211;167;165;168;166;165;165 18:31:53 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;69;68;68;67;68;67;66;66;67;66 18:31:53 INFO - PROCESS | 1656 | __end_tp_report 18:31:53 INFO - PROCESS | 1656 | __start_cc_report 18:31:53 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2018 18:31:53 INFO - PROCESS | 1656 | __end_cc_report 18:31:53 INFO - PROCESS | 1656 | __startTimestamp1483410713951__endTimestamp 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | ------- Summary: start ------- 18:31:53 INFO - PROCESS | 1656 | Number of tests: 14 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.03 (0.9%) stddev-sans-first:0.73 18:31:53 INFO - PROCESS | 1656 | Values: 116.0 114.0 114.0 114.0 113.0 114.0 114.0 113.0 114.0 112.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:123.00 stddev:17.44 (14.2%) stddev-sans-first:15.64 18:31:53 INFO - PROCESS | 1656 | Values: 149.0 110.0 112.0 146.0 108.0 104.0 133.0 113.0 140.0 110.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:194.70 Median:185.00 stddev:25.80 (13.9%) stddev-sans-first:27.02 18:31:53 INFO - PROCESS | 1656 | Values: 183.0 185.0 182.0 185.0 185.0 184.0 184.0 184.0 211.0 264.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:79.90 Median:74.00 stddev:19.99 (27.0%) stddev-sans-first:21.15 18:31:53 INFO - PROCESS | 1656 | Values: 76.0 62.0 81.0 115.0 51.0 73.0 75.0 83.0 112.0 71.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.50 stddev:11.60 (14.6%) stddev-sans-first:11.73 18:31:53 INFO - PROCESS | 1656 | Values: 88.0 71.0 95.0 69.0 69.0 70.0 89.0 67.0 69.0 93.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:1.45 (1.6%) stddev-sans-first:1.50 18:31:53 INFO - PROCESS | 1656 | Values: 89.0 87.0 90.0 87.0 89.0 86.0 90.0 87.0 89.0 87.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:95.00 stddev:3.00 (3.2%) stddev-sans-first:3.16 18:31:53 INFO - PROCESS | 1656 | Values: 95.0 95.0 95.0 95.0 93.0 96.0 96.0 96.0 94.0 104.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:82.40 Median:82.00 stddev:2.76 (3.4%) stddev-sans-first:2.88 18:31:53 INFO - PROCESS | 1656 | Values: 81.0 83.0 80.0 80.0 81.0 81.0 89.0 84.0 81.0 84.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:57.00 stddev:2.23 (3.9%) stddev-sans-first:2.35 18:31:53 INFO - PROCESS | 1656 | Values: 55.0 52.0 53.0 54.0 58.0 58.0 57.0 57.0 58.0 57.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.50 stddev:2.50 (3.5%) stddev-sans-first:1.73 18:31:53 INFO - PROCESS | 1656 | Values: 65.0 72.0 71.0 74.0 72.0 72.0 68.0 70.0 70.0 70.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.00 stddev:1.16 (1.4%) stddev-sans-first:1.20 18:31:53 INFO - PROCESS | 1656 | Values: 85.0 84.0 84.0 85.0 83.0 83.0 84.0 84.0 87.0 84.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:143.50 stddev:15.52 (10.8%) stddev-sans-first:16.36 18:31:53 INFO - PROCESS | 1656 | Values: 142.0 139.0 137.0 139.0 138.0 145.0 145.0 162.0 186.0 138.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.30 Median:167.50 stddev:14.17 (8.5%) stddev-sans-first:14.84 18:31:53 INFO - PROCESS | 1656 | Values: 165.0 168.0 173.0 211.0 167.0 165.0 168.0 166.0 165.0 165.0 18:31:53 INFO - PROCESS | 1656 | 18:31:53 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.03 (1.5%) stddev-sans-first:0.87 18:31:53 INFO - PROCESS | 1656 | Values: 69.0 68.0 68.0 67.0 68.0 67.0 66.0 66.0 67.0 66.0 18:31:53 INFO - PROCESS | 1656 | -------- Summary: end -------- 18:31:53 INFO - PROCESS | 1656 | 18:31:54 INFO - TEST-INFO | 1656: exit 0 18:31:55 INFO - TEST-OK | kraken | took 59627ms 18:31:55 INFO - SUITE-END | took 1179s 18:31:55 INFO - Completed test suite (00:19:40) 18:31:55 INFO - javascript benchmark 18:31:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.432296509594, 3347.995601319605, 3366.2425954160303, 3372.0221135870597, 3426.1263377838645, 5612.298462692163, 5693.174489698991, 5710.200571919934, 5725.7995970141055, 5748.683822389767, 5654.830355089343, 5749.511291540213, 5755.338136114348, 5763.9711801440935, 5785.045467497858, 5163.63854530183, 5243.318368612075, 5250.448702886209, 5254.159334506479, 5313.070212712779, 5172.534471897528, 5203.245529398235, 5211.895762084756, 5265.420803711589, 5350.0904846176045, 5249.081410753122, 5256.185291279858, 5278.498542638443, 5293.232481290211, 5294.311739473863, 5269.1832765921345, 5341.786328546854, 5353.732313384316, 5360.839174824722, 5374.946250537484, 5065.670731402447, 5199.246109314128, 5248.239005344242, 5270.945810837848, 5288.23320618512, 5649.689267090308, 5670.404607516192, 5691.26013618227, 5710.343310519302, 5714.657120572738, 6215.87568248637, 6235.158253635746, 6295.937040629581, 6313.558050936436, 6334.873302533923, 6197.752089916443, 6335.619862808247, 6384.840378990516, 6393.7442502299855, 6399.968000159969, 5323.920141197885, 5780.50865676418, 5790.9286781945475, 5864.179014937912, 5867.0, 6308.161327402596, 6325.810225693236, 6335.334789847092, 6356.300806911236, 6358.046293056079, 6031.4270144336215, 6201.565890387629, 6230.470410015108, 6248.625082495065, 6252.812415627538, 6213.689315534296, 6214.130021796952, 6262.968685156545, 6263.498920086382, 6300.621962682162, 6239.656818875005, 6302.243730752339, 6308.02225655015, 6313.558050936482, 6333.0, 6299.968500157562, 6300.527460440485, 6336.493080553545, 6337.271213810471, 6354.205724284465, 4854.2233242681705, 4910.042541704404, 4918.729469879119, 4932.038252540792, 4998.4251811042195, 4446.155230506193, 4463.196624607537, 4469.888252793706, 4495.775211239425, 4497.8425755098415, 5718.7426565804635, 5718.79984200551, 5727.971360143172, 5729.455701708331, 5735.053716136874, 5160.303359046556, 6281.434670879643, 6282.780102696383, 6289.0252010938375, 6289.528285378615, 5734.827955161351, 5745.253117094751, 5783.074708046776, 5827.067669172912, 5831.708414579254, 5417.052015897202, 5430.70131142791, 5432.2123292123215, 5445.700486473282, 5465.125579907196, 6396.848567257938, 6405.583637063576, 6408.807735767934, 6419.839504012437, 6426.710798014007, 6144.723487442986, 6185.319614842454, 6230.470410015199, 6238.75044998195, 6355.713992870425, 6369.936300636934, 6370.840728981812, 6371.808845734636, 6375.234971803413, 6385.425311721968, 6245.531585131042, 6246.5002799775, 6252.499800015897, 6268.467180289544, 6270.372962703876, 6144.7571636353305, 6155.599886007395, 6180.969095154495, 6342.112104305309, 6350.618962862243, 4646.62826973848, 6061.969690151521, 6100.420460056288, 6146.432659671934, 6156.26124865019, 6123.8142785821265, 6125.693326622233, 6127.525510585677, 6131.957816599936, 6142.682951206477, 5905.586608937333, 5993.101034844808, 5994.220751302303, 6039.365866584121, 6047.334793172659, 6097.878042439215, 6112.047575716758, 6117.082437634391, 6129.481048574228, 6139.6454669416935, 5519.806806761744, 5651.2935883014625, 6021.126936594082, 6024.3975602439405, 6072.332043475126], "value": 5728.168367856879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.0680134948995, 515.0728688360952, 517.4179048570359, 517.9145441002237, 527.3197575128085, 26538.73461265387, 31347.0, 32359.191020224447, 32381.51427728586, 32382.514262286084, 32831.34337313252, 33494.832525837395, 33518.83240583794, 33524.16189595255, 42210.0, 7967.60161991901, 9138.680146194885, 9303.395279306824, 9313.441193528377, 9329.766755831091, 9185.586648600829, 9273.582688778988, 9274.907250927472, 9300.813983720322, 9304.395214311047, 7972.1629228931, 9257.953710231439, 9276.953615231914, 9303.348765586412, 9317.534123293843, 9174.724758257264, 9229.723108306727, 9240.445573265559, 9240.95379523098, 9253.16721495062, 9217.953910230406, 9297.395669281475, 9301.441913485212, 9306.069393060707, 9315.0, 40001.99995000119, 40332.798336008425, 40368.99077523056, 40391.596084039076, 40397.394039089435, 3469.583649962021, 3838.9059118151195, 3856.936199930142, 3895.3183192941124, 3907.2380885727293, 28706.425871482454, 29467.70532294671, 29622.25944351387, 29724.86218164588, 30560.8471957641, 3162.3201011782576, 3170.1282147409415, 3175.0474857542645, 3212.132724164486, 3221.7100460958627, 483714.0, 492336.0, 492954.0, 493003.9999999964, 494737.9999999964, 62144.75710485855, 62667.43331416751, 62733.372666273666, 62753.0, 62796.0, 34723.0, 35558.644413555536, 35561.82219088888, 36543.81728091343, 65631.67184164144, 13764.242966636732, 15800.36798528069, 16031.038137711774, 16062.518124456285, 16070.035797852166, 12178.391080445943, 12290.20113692607, 12296.631101066982, 12321.075919306088, 12330.321832299309, 15761.921190393974, 19195.904020480088, 19284.710729339073, 19294.32469863548, 19323.4202973911, 6292.874142517124, 7427.25727427253, 7558.13081495624, 7565.43259255547, 7624.771256862303, 822.1737154160031, 937.254882368505, 951.9571619277149, 994.5673631969971, 1085.4301491716913, 29095.0, 29639.703602964124, 30207.244818879703, 30278.84860575727, 30758.0, 1058.8358804385332, 1076.6231818863334, 1089.6022951622601, 1106.2919731371928, 1111.77208672222, 15030.774538381933, 15494.0, 15552.222388880511, 15769.68460630781, 15777.368905243891, 681220.0, 685332.0, 688434.0, 689833.5508322426, 690638.0, 58450.4154958445, 58935.70532147312, 59072.40927590755, 59160.40839591549, 59277.703611481666, 1155.4569352404355, 1203.2599951030054, 1250.2186133666457, 1252.273681264864, 1378.0973462382156, 29907.91565433925, 31644.841775790974, 32045.519317209793, 32064.717411303285, 33101.0, 56162.7191864038, 57336.42663573311, 59609.0, 59726.0, 59796.70101649464, 509133.0, 607815.0, 608525.0, 608884.0, 609996.0, 35916.82041589775, 36378.27243455169, 43467.0, 45271.0, 45521.31718024166, 42997.785011074746, 46694.76652616851, 47360.0, 47502.0, 47899.0, 38075.80962095172, 38791.6120838788, 38865.222695546494, 39530.802345988086, 40195.1960960785, 33095.834520827244, 38806.64176753913, 39372.60627393689, 40810.9797255071, 40834.9791255221, 38089.66744655695, 38140.23247094113, 38402.0, 38538.843834685, 39016.0, 32994.505082424264, 34115.31769364649, 34871.47692784661, 34906.9527914163, 35005.824970876005, 21186.0, 25939.0, 26002.60996085098, 26056.478870422863, 26196.476070478104], "value": 19765.60031941701, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.90080624603246, 343.1062083273075, 370.39810308249093, 370.57013863917854, 401.2296390929415, 14365.928170359146, 14541.054831435962, 15636.687266254683, 16286.592835179126, 20038.1984720611, 16745.748813767805, 19507.5123121922, 19935.40193794185, 20305.49236269094, 20393.796062039375, 5363.168708850135, 5653.321601407837, 5678.914816277738, 5679.687617181054, 5683.545316374701, 5325.174597937326, 5527.834164975057, 5567.3019728982645, 5591.664500129985, 5597.916031259534, 5523.199136125259, 5523.420040895709, 5524.696141712204, 5528.419515950827, 5532.889342213173, 5064.56951159152, 5292.92060619091, 5345.503259087462, 5504.4495550445035, 5511.200875872996, 4210.347396153601, 4931.605471562267, 4936.975315123437, 4941.45643979162, 4949.084419382426, 24698.382540436454, 24978.250652480274, 25058.624120638204, 25066.624000640004, 25330.0, 2265.7960783529397, 2404.2923865728953, 2465.8767061646845, 2620.3711109333813, 2674.558697814858, 9522.190613797835, 10042.397456152654, 14421.278936053155, 15012.849871501254, 15865.048097114095, 1735.8177391373977, 1774.8402643761995, 1794.5423916901236, 1803.4048763907879, 1813.954651133719, 107429.0, 110337.0, 111056.4447177759, 111822.99999999919, 111856.4407177959, 11210.439478026066, 11267.718307042225, 11301.604443844426, 11367.488463019185, 11460.828087578693, 14265.288165420216, 14273.000889937604, 14283.0, 14294.714105718036, 14305.85694143066, 6950.721971121098, 8278.172182781673, 8313.12712165226, 8326.833463330699, 8355.164483551596, 8168.632411541495, 8193.344532437392, 8328.375371847014, 8340.833183336421, 8342.290905273, 4299.376590394375, 4403.823847046082, 4413.22768515515, 4446.888827779331, 4529.886752831139, 1556.2763315058326, 1806.6296409236074, 1869.1495369606796, 1869.3363855831255, 1907.523119220195, 803.4536515169624, 874.226309715906, 914.0950459045497, 961.5480724059681, 983.188869182927, 19997.400077997685, 20357.89821050915, 20392.694109588367, 20447.795522044587, 20490.59018819615, 901.324006994754, 906.4816432475709, 925.8194652042922, 944.244604316544, 953.7567920180448, 10042.447665378328, 10516.316439431412, 10613.310134841211, 10680.0, 10700.892991070144, 570565.1471742615, 581616.0, 590770.0, 593566.064339351, 594233.0, 86342.56828715817, 96997.51501242448, 115777.89554731257, 119087.0, 120579.0, 902.9051949545335, 913.3925939250457, 953.3040880157524, 957.4111921168432, 958.4632605740808, 17941.372051978116, 18245.270189192284, 18467.16897739578, 18502.074896254864, 18539.907300463412, 48737.5126248733, 49815.75092124516, 49949.500504995936, 50230.748846255534, 50645.746771265905, 67725.98411023937, 72794.90807637783, 73701.0, 73839.26160738539, 74684.0, 7447.180810110775, 7475.327220550176, 7550.056242969629, 7566.35685966677, 7732.613369331399, 6968.540278921158, 11527.02020328248, 11686.006689431493, 12166.330851803235, 12175.695607609881, 10001.449920254456, 10021.799564008825, 10087.0, 10178.88812164993, 10365.533550990374, 9967.900320996698, 10019.348742331726, 10027.799444010932, 10254.384736915808, 10265.332753371007, 8440.83118337642, 9997.500124993576, 10241.617381653381, 10253.33353332031, 10488.31825931312, 7496.362809161283, 7635.961820190863, 7658.387329013554, 7698.153203147761, 7792.649330780015, 9400.623975040922, 10275.280730348804, 10441.399547409921, 10481.056704896597, 10725.30285531438], "value": 9083.575602528503, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.2163431746853, 1477.3204326010034, 1620.4085508789287, 1766.7668167253926, 1822.4532640207935, 8020.157883422245, 8463.449875758071, 8466.788330291745, 8473.872891906627, 8481.95759021204, 7420.703171873132, 7637.045369328834, 7653.4259930505295, 7681.923180768191, 8240.50556966581, 2273.0794028418477, 2310.1452462588863, 2314.9537009259807, 2317.0, 2325.3256555598855, 2296.276672848056, 2329.301209637111, 2338.4153961509624, 2338.4504641409253, 2349.177787774274, 2284.2690339091414, 2290.7823756743082, 2307.227078928561, 2314.2363020203375, 2320.8491448055906, 2297.873616951067, 2304.331743794298, 2310.0644239083113, 2318.5362927414585, 2319.0028287836226, 2301.3326135420707, 2307.5846347657416, 2324.3491822289784, 2333.101755824003, 2333.7666233376694, 8004.839903201903, 8031.397645176577, 8033.95983020087, 8039.839203215962, 8070.838583228303, 4964.851054468371, 5179.326687530634, 5187.429382767892, 5203.635745497817, 5208.0, 3091.2426455518316, 3183.3792410479964, 3228.9192770180935, 3456.654334566548, 3517.2437925846066, 995.8804943406765, 1049.5329578337644, 1051.5846240734943, 1069.7913906788178, 1087.7770057138268, 10968.067714244298, 11006.449677516172, 11008.559657613685, 11034.448277586169, 11048.06091482225, 4513.187626227311, 4611.584957353854, 4690.0, 4746.501617330199, 4759.214729569582, 9394.812103758024, 9397.859032114524, 9407.529623518796, 9417.717468475958, 9453.810923781486, 5169.534741873174, 5255.47445255479, 5256.868578285497, 5259.132243179833, 5268.6575372600655, 3973.523177218752, 4493.910121797611, 4699.530046995342, 4745.857624271277, 4794.472608013116, 2808.6208361870886, 3153.274746808201, 3614.367485689994, 3799.525059367579, 3807.0672685192303, 1229.9631011069682, 1233.210745123122, 1241.8229285579573, 1249.1068885746736, 1251.0, 789.2226157235119, 892.9464232146092, 897.3718397122041, 903.3992395057213, 916.9403988740711, 5502.257195278667, 5587.972060139673, 5633.295838020247, 5639.689817060019, 5846.1523079153285, 434.7282948157402, 438.3030980740574, 442.4253221687764, 448.3924282597099, 451.26669162610756, 3781.7541859779026, 3816.3321418751607, 3832.9041773955873, 3851.7496362736333, 3902.2195560887935, 9797.06927841854, 10103.84844227337, 10229.948850255701, 10235.846462303072, 10246.897531024744, 10182.592696292066, 10189.796204075878, 10198.929112443087, 10199.235057370579, 10201.795964080677, 7559.20628334028, 7749.690012399441, 7829.882551761728, 7832.216778322236, 7899.368050555941, 5797.507211887037, 5805.187273781589, 5874.706264686834, 5924.022536281465, 5953.76184952597, 9016.2786977043, 9300.72097837066, 9415.19970802462, 9463.763405914908, 9471.952640236756, 8722.258608018135, 9480.52597370115, 9586.28102892272, 9617.615295388106, 9646.469444180637, 6742.359475849787, 6815.965920170367, 6818.454523638196, 6901.792946211421, 7185.2814718527725, 6655.467562595078, 7884.84230315379, 7901.525908445512, 7969.123396426505, 8006.039275287003, 7172.9641351795, 7190.7483238086415, 7200.71197152108, 7209.459290553124, 7237.8914316284245, 6979.40675042627, 7019.613921234177, 7046.541974771623, 7126.5011449198055, 7159.176694680178, 6594.538382313192, 6633.3035031321015, 6711.798646040626, 6719.496037797087, 6724.630145341857, 4756.500567440507, 4848.10310092634, 4852.053849499313, 4883.5116488352305, 4968.354113965162, 4530.705504142352, 4541.863744087683, 4554.27131658933, 4688.578027977362, 4744.074905393415], "value": 4310.449499080323, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.7046924556102, 300.1011969152389, 304.28341256341315, 308.1448979083044, 312.3783670495711, 4249.830006799728, 4255.935871102521, 4984.651074424787, 5112.386513618371, 5113.514216149464, 3959.7624142551467, 4095.8771236862867, 4805.639577031728, 4891.926621100677, 5043.344365232515, 3738.9813050934845, 3936.3701807710772, 3937.0551067743677, 3955.6835453163676, 3956.3669812829953, 3932.488776459056, 3946.3488524393442, 3956.4263181838614, 3970.4838371011874, 3974.662153716923, 3403.6596340366013, 3645.2891686121216, 3668.19299754055, 3912.8261521543564, 3951.6443520083185, 3868.574456809749, 3880.341965753468, 3885.2812229737447, 3889.2027134437653, 3903.843846246161, 3005.2637103909683, 3357.983210083934, 3360.4959256111533, 3385.64450732672, 3419.6238413774463, 4093.0557984957204, 4458.019235768126, 4573.794179261941, 4932.334134891816, 4980.228064649985, 3721.6464435878547, 4143.958560414418, 4322.897661096432, 4437.378766972626, 4489.528599497038, 4639.559241872017, 5290.920636190422, 5333.946430624238, 5385.353757549119, 5395.973020134874, 3658.396364599838, 3679.7608155469807, 3682.5143028455686, 3699.4635777812337, 3724.2923844469465, 5360.490753378423, 5518.751656175482, 5538.483063254782, 5541.584381171388, 5608.523275521586, 3756.760269111187, 3769.2838360711926, 3772.7170462215445, 3776.263628592398, 3777.376732839106, 3770.472133901256, 3779.4708740776314, 3782.2813665403487, 3799.2781371539318, 3805.238952209569, 4182.728122672017, 4388.4733831940375, 4405.713628614129, 4416.293393057095, 4428.4685837699035, 4676.906461870743, 4947.282644016597, 5368.892622147535, 5390.574241591221, 5437.238786569884, 1014.3964341216964, 1058.4548957287004, 1215.769003889258, 1228.7419641875306, 1260.8108783682521, 684.7021545627667, 813.7965508622844, 842.8145807922248, 843.401185158532, 845.6997765793178, 4330.453024324447, 4359.476862776487, 4503.392042074278, 4648.814047438064, 4864.489228631013, 5183.248428977777, 5228.215767634885, 5230.738463076831, 5251.0, 5324.524096295164, 3975.940360894589, 4215.473065866767, 4705.529447055267, 4776.8566942991765, 5143.305653736773, 2441.827922597128, 2538.860362680033, 2754.449110177972, 2797.846118463504, 2810.718928107173, 5201.902135906833, 5281.313429254249, 5297.337832770903, 5333.493318134771, 5366.275552800322, 5244.580433565306, 5324.547413469821, 5696.031674615326, 5715.228444159985, 5795.681237531892, 5684.971575142181, 5685.886282274414, 5688.77244910199, 5690.715464226772, 5711.230053424003, 5395.757190926496, 5456.574555079262, 5551.417101204477, 5551.417845913895, 5552.194931734956, 4551.40831691878, 5437.564994800327, 5447.509724124848, 5463.617546771688, 5550.750216240198, 4673.102676340554, 5079.390473143247, 5715.799682066646, 5732.59231122921, 5755.625884317506, 3262.722668573198, 3434.9656503434644, 3783.6027217142905, 3892.6885849131436, 3953.0, 5454.890902182014, 5456.263404440509, 5486.506214440719, 5510.283663123768, 5545.918545883514, 5132.610758703421, 5314.760835762323, 5333.599980001438, 5334.226537152169, 5359.650218613367, 5126.871828204325, 5140.0, 5155.690658560498, 5212.54048866318, 5335.653182543122, 5152.793888244429, 5239.580833533384, 5248.448912864094, 5269.789208431773, 5272.393674727302, 4170.624643782075, 4979.502049794992, 4981.302617633607, 5248.582882621748, 5373.650712703662, 4758.52485729425, 4811.230203167476, 4954.8761280968265, 5226.66719986401, 5240.187770895517], "value": 3882.9739106314937, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.8406782372232, 696.578569965171, 707.0560801330221, 731.8499707559951, 762.8474305138972, 13889.444422223112, 14346.0, 14346.713065738679, 14381.424743010268, 14385.0, 12840.165389249692, 12867.806982895265, 12917.935410322958, 12976.93511532441, 13015.219086854771, 630.7792272704539, 650.7462089784988, 652.7193306878064, 787.4251796188786, 796.1958421993785, 814.2997022560593, 819.7212947597816, 826.4132465949183, 828.0684230240979, 831.1397703377006, 872.9127087291283, 876.1895246896595, 877.8814859993915, 879.4459490520962, 880.5641207602246, 670.6680193304297, 692.0864458914235, 874.1433395272637, 874.6020560644891, 874.8687696845492, 620.8913440147955, 637.5314144104126, 639.6290151712043, 641.3458272562008, 642.8103709405736, 13858.722825543533, 13907.513237036655, 13909.026368154233, 13914.93042534791, 13936.442542298297, 12739.108262421634, 12926.482940682361, 13111.868881311255, 13113.409896554582, 13122.2126672399, 10759.193060520493, 11050.005499505083, 11135.164862635254, 11478.0, 11687.50093641845, 4298.978505107454, 4506.211413002744, 4508.0, 4609.03210325832, 4631.8147274109, 66657.66671166662, 83437.3312533752, 85516.57241713752, 85701.28597428079, 87342.56328718379, 9942.552585133686, 10182.490875456198, 10367.222458315657, 10483.947580262049, 10558.366498009991, 19185.424437266905, 19679.803201968083, 19961.800381995996, 20687.8965605174, 20703.0, 7417.554946703215, 7496.25037496246, 7677.769666910001, 7836.216378362232, 7838.255365740246, 3635.9092272318726, 3874.2638898609175, 3956.6043395660204, 4239.618434340924, 4292.784267115469, 3381.526586277923, 3513.631068737797, 3514.7012503937453, 3515.8593656253465, 3517.982410087933, 638.8977763557901, 652.5921299188008, 663.1048085085097, 667.9599224046573, 670.0987172253311, 564.1960206705428, 577.4773829684143, 626.3861415812529, 647.414090248326, 656.2158220925947, 7906.0, 8497.532635705096, 8561.529115898686, 8700.390972631854, 9026.684066057656, 552.6407834907343, 658.6443320606915, 659.2846761263986, 662.059874977533, 667.5927684112687, 4084.8766589187617, 4265.76538290397, 4458.843940462133, 4466.6203372713035, 4493.348464472699, 90469.0, 96786.51606741922, 110042.44978775215, 110245.89754102517, 119760.0, 92488.07511924929, 95167.04832951582, 95684.0, 96463.0, 97398.51300743593, 90488.54755726179, 92467.5376623126, 92641.5367923156, 93040.0, 95128.9140423058, 8511.914880851113, 8523.573821308786, 8710.21608055278, 8737.995130560066, 8753.0, 48449.5155048445, 57935.1309730346, 58190.70904645619, 59068.0, 61394.77210455855, 80012.0, 82907.5854620723, 84381.0, 85310.14689853022, 87630.0, 10975.890241097806, 11073.003429691367, 11084.224104312621, 11140.610078647533, 11464.968152866282, 10419.53112109972, 11252.324860508395, 11528.07775377951, 11602.94198529002, 11970.042396608425, 11916.642500724993, 11974.880251197725, 12226.510939562673, 12488.0, 12507.874921251036, 11839.408029598659, 11987.76024479523, 12457.941075008726, 12482.375881206084, 12547.811782823448, 11869.762604747684, 11934.582289619822, 12352.814707779211, 12391.256524608552, 12418.006559475401, 9497.14525692691, 9659.565319560777, 9944.403335799876, 10027.0, 10297.279190456597, 8422.284105850826, 10073.395596264247, 10148.492575371054, 10151.89848101539, 10157.746056348355], "value": 7112.733856379943, "unit": "score"}], "name": "dromaeo_css", "value": 7046.810825388091, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 112.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 110.0, 112.0, 146.0, 108.0, 104.0, 133.0, 113.0, 140.0, 110.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 182.0, 185.0, 185.0, 184.0, 184.0, 184.0, 211.0, 264.0], "value": 194.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 62.0, 81.0, 115.0, 51.0, 73.0, 75.0, 83.0, 112.0, 71.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 95.0, 69.0, 69.0, 70.0, 89.0, 67.0, 69.0, 93.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 93.0, 96.0, 96.0, 96.0, 94.0, 104.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 80.0, 80.0, 81.0, 81.0, 89.0, 84.0, 81.0, 84.0], "value": 82.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 54.0, 58.0, 58.0, 57.0, 57.0, 58.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 71.0, 74.0, 72.0, 72.0, 68.0, 70.0, 70.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 85.0, 83.0, 83.0, 84.0, 84.0, 87.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 139.0, 137.0, 139.0, 138.0, 145.0, 145.0, 162.0, 186.0, 138.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 173.0, 211.0, 167.0, 165.0, 168.0, 166.0, 165.0, 165.0], "value": 171.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 68.0, 67.0, 68.0, 67.0, 66.0, 66.0, 67.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1451.4999999999998, "alertThreshold": 2.0}]} 18:31:55 INFO - Return code: 0 18:31:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:31:55 INFO - # TBPL SUCCESS # 18:31:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:31:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:31:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:31:55 INFO - 2017-01-02 18:31:55.250 screenresolution[1660:10831] starting screenresolution argv=screenresolution get 18:31:55 INFO - 2017-01-02 18:31:55.253 screenresolution[1660:10831] Display 0: 1600x1200x32@60 18:31:55 INFO - 2017-01-02 18:31:55.259 screenresolution[1661:10835] starting screenresolution argv=screenresolution list 18:31:55 INFO - Available Modes on Display 0 18:31:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:31:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:31:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:31:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:31:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:31:55 INFO - Intel Iris: 18:31:55 INFO - Chipset Model: Intel Iris 18:31:55 INFO - Type: GPU 18:31:55 INFO - Bus: Built-In 18:31:55 INFO - VRAM (Dynamic, Max): 1536 MB 18:31:55 INFO - Vendor: Intel (0x8086) 18:31:55 INFO - Device ID: 0x0a2e 18:31:55 INFO - Revision ID: 0x0009 18:31:55 INFO - Displays: 18:31:55 INFO - Display: 18:31:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:31:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:31:55 INFO - Display Serial Number: 1600x1200 60 18:31:55 INFO - Main Display: Yes 18:31:55 INFO - Mirror: Off 18:31:55 INFO - Online: Yes 18:31:55 INFO - Rotation: Supported 18:31:55 INFO - Return code: 0 18:31:55 INFO - Running post-action listener: _resource_record_post_action 18:31:55 INFO - [mozharness: 2017-01-03 02:31:55.503860Z] Finished run-tests step (success) 18:31:55 INFO - Running post-run listener: _resource_record_post_run 18:31:55 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33971200; Write bytes: 659230208; Read time: 320; Write time: 2027 18:31:55 INFO - TinderboxPrint: CPU usage
53.5% 18:31:55 INFO - TinderboxPrint: I/O read bytes / time
33,971,200 / 320 18:31:55 INFO - TinderboxPrint: I/O write bytes / time
659,230,208 / 2,027 18:31:55 INFO - TinderboxPrint: CPU idle
2,254.8 (46.5%) 18:31:55 INFO - TinderboxPrint: CPU system
976.3 (20.1%) 18:31:55 INFO - TinderboxPrint: CPU user
1,618.5 (33.4%) 18:31:55 INFO - TinderboxPrint: Swap in / out
379,297,792 / 0 18:31:55 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 334590464; Write bytes: 334149120; Read time: 26424; Write time: 600 18:31:55 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 33029120; Write bytes: 307386368; Read time: 304; Write time: 1375 18:31:55 INFO - Running post-run listener: _upload_blobber_files 18:31:55 INFO - Blob upload gear active. 18:31:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:31:55 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 18:31:55 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:31:55 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:31:56 INFO - (blobuploader) - INFO - Open directory for files ... 18:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:57 INFO - (blobuploader) - INFO - Done attempting. 18:31:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:31:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:31:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:31:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:31:58 INFO - (blobuploader) - INFO - Done attempting. 18:31:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:31:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:31:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:32:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:00 INFO - (blobuploader) - INFO - Done attempting. 18:32:00 INFO - (blobuploader) - INFO - Iteration through files over. 18:32:00 INFO - Return code: 0 18:32:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:32:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:32:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa1f4767d836bc3b354ee9e0f452e824daea4faef935fb053ab6f74fd3adbdc158683809e10516c18a5fc3df399bd67463e6597bfc7692c238ebb93642cd404d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfb3864801fab1770b651f14e68a3d1d751e5800140090492385f4ef37f81cdd3aee296df2c4785199191fd164fe9a219d59bf93589da4722676db1fbb2cc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add3b83ee8feaea72011a6ffef6c564a859ddc98b039a54b0b57de081874bbbf4c9fc2e65e5782eec29b3da157f9d80a4368074f46d6150fc2d173baa2cf770"} 18:32:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:00 INFO - Contents: 18:32:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa1f4767d836bc3b354ee9e0f452e824daea4faef935fb053ab6f74fd3adbdc158683809e10516c18a5fc3df399bd67463e6597bfc7692c238ebb93642cd404d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfb3864801fab1770b651f14e68a3d1d751e5800140090492385f4ef37f81cdd3aee296df2c4785199191fd164fe9a219d59bf93589da4722676db1fbb2cc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add3b83ee8feaea72011a6ffef6c564a859ddc98b039a54b0b57de081874bbbf4c9fc2e65e5782eec29b3da157f9d80a4368074f46d6150fc2d173baa2cf770"} 18:32:00 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:00 INFO - Copying logs to upload dir... 18:32:00 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.604417 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-02 18:32:00.324549) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 18:32:00.328384) ========= 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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa1f4767d836bc3b354ee9e0f452e824daea4faef935fb053ab6f74fd3adbdc158683809e10516c18a5fc3df399bd67463e6597bfc7692c238ebb93642cd404d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfb3864801fab1770b651f14e68a3d1d751e5800140090492385f4ef37f81cdd3aee296df2c4785199191fd164fe9a219d59bf93589da4722676db1fbb2cc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add3b83ee8feaea72011a6ffef6c564a859ddc98b039a54b0b57de081874bbbf4c9fc2e65e5782eec29b3da157f9d80a4368074f46d6150fc2d173baa2cf770"} build_url:https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010520 build_url: 'https://queue.taskcluster.net/v1/task/BAuG6qjjSLKbf2VnjZIKlg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa1f4767d836bc3b354ee9e0f452e824daea4faef935fb053ab6f74fd3adbdc158683809e10516c18a5fc3df399bd67463e6597bfc7692c238ebb93642cd404d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4dfb3864801fab1770b651f14e68a3d1d751e5800140090492385f4ef37f81cdd3aee296df2c4785199191fd164fe9a219d59bf93589da4722676db1fbb2cc80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8add3b83ee8feaea72011a6ffef6c564a859ddc98b039a54b0b57de081874bbbf4c9fc2e65e5782eec29b3da157f9d80a4368074f46d6150fc2d173baa2cf770"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-02 18:32:00.361662) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:32:00.362000) ========= 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.fFxkQ3Nclf/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.Axgf8QbgGK/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005278 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-02 18:32:00.410807) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 18:32:00.411129) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-02 18:32:00.411462) ========= ========= Total master_lag: 0.20 =========