builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0098 starttime: 1486375179.85 results: success (0) buildid: 20170206004003 builduid: 88237725478d492189fc0c031a7cb528 revision: c3d208c04d8b68ed20a0b62c2f9a5bcc83aa9349 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:39.850260) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:39.850724) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:39.851027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:39.875660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:39.876072) ========= 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.2Zx5VVDhpx/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.OFVAHovodq/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-06 01:59:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.22M=0.001s 2017-02-06 01:59:40 (8.22 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.339659 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:40.239137) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:40.239453) ========= 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.2Zx5VVDhpx/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.OFVAHovodq/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.020051 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:40.287772) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-06 01:59:40.288145) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c3d208c04d8b68ed20a0b62c2f9a5bcc83aa9349 --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 c3d208c04d8b68ed20a0b62c2f9a5bcc83aa9349 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2Zx5VVDhpx/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.OFVAHovodq/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-06 01:59:40,371 truncating revision to first 12 chars 2017-02-06 01:59:40,371 Setting DEBUG logging. 2017-02-06 01:59:40,371 attempt 1/10 2017-02-06 01:59:40,371 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c3d208c04d8b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 01:59:42,323 unpacking tar archive at: mozilla-aurora-c3d208c04d8b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.199089 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-06 01:59:42.700380) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:42.700935) ========= script_repo_revision: c3d208c04d8b68ed20a0b62c2f9a5bcc83aa9349 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:42.701316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:42.701582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 01:59:42.721399) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-06 01:59:42.721780) ========= /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.2Zx5VVDhpx/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.OFVAHovodq/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 01:59:42 INFO - MultiFileLogger online at 20170206 01:59:42 in /builds/slave/test 01:59:42 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 01:59:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:59:42 INFO - {'append_to_log': False, 01:59:42 INFO - 'base_work_dir': '/builds/slave/test', 01:59:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:59:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:59:42 INFO - 'branch': 'Mozilla-Aurora', 01:59:42 INFO - 'buildbot_json_path': 'buildprops.json', 01:59:42 INFO - 'config_files': ('talos/mac_config.py',), 01:59:42 INFO - 'default_actions': ('clobber', 01:59:42 INFO - 'read-buildbot-config', 01:59:42 INFO - 'download-and-extract', 01:59:42 INFO - 'populate-webroot', 01:59:42 INFO - 'create-virtualenv', 01:59:42 INFO - 'install', 01:59:42 INFO - 'run-tests'), 01:59:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:59:42 INFO - 'download_minidump_stackwalk': True, 01:59:42 INFO - 'download_symbols': 'ondemand', 01:59:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:59:42 INFO - 'tooltool.py': '/tools/tooltool.py', 01:59:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:59:42 INFO - '/tools/misc-python/virtualenv.py')}, 01:59:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:59:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:59:42 INFO - 'installer_path': 'installer.exe', 01:59:42 INFO - 'log_level': 'info', 01:59:42 INFO - 'log_name': 'talos', 01:59:42 INFO - 'log_to_console': True, 01:59:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:59:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:59:42 INFO - 'opt_config_files': (), 01:59:42 INFO - 'pip_index': False, 01:59:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:59:42 INFO - 'cmd': ('bash', 01:59:42 INFO - '-c', 01:59:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:59:42 INFO - 'enabled': True, 01:59:42 INFO - 'halt_on_failure': False, 01:59:42 INFO - 'name': 'check_screen_resolution'},), 01:59:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:59:42 INFO - 'cmd': ('bash', 01:59:42 INFO - '-c', 01:59:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:59:42 INFO - 'enabled': True, 01:59:42 INFO - 'halt_on_failure': False, 01:59:42 INFO - 'name': 'check_screen_resolution'},), 01:59:42 INFO - 'run_cmd_checks_enabled': True, 01:59:42 INFO - 'sps_profile': False, 01:59:42 INFO - 'sps_profile_interval': 0, 01:59:42 INFO - 'suite': 'dromaeojs', 01:59:42 INFO - 'system_bits': '32', 01:59:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:59:42 INFO - 'title': 't-yosemite-r7-0098', 01:59:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:59:42 INFO - 'use_talos_json': True, 01:59:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:59:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:59:42 INFO - 'work_dir': 'build'} 01:59:42 INFO - [mozharness: 2017-02-06 09:59:42.934235Z] Running clobber step. 01:59:42 INFO - Running pre-action listener: _resource_record_pre_action 01:59:42 INFO - Running main action method: clobber 01:59:42 INFO - rmtree: /builds/slave/test/build 01:59:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:59:44 INFO - Running post-action listener: _resource_record_post_action 01:59:44 INFO - [mozharness: 2017-02-06 09:59:44.597434Z] Finished clobber step (success) 01:59:44 INFO - [mozharness: 2017-02-06 09:59:44.597566Z] Running read-buildbot-config step. 01:59:44 INFO - Running pre-action listener: _resource_record_pre_action 01:59:44 INFO - Running main action method: read_buildbot_config 01:59:44 INFO - Using buildbot properties: 01:59:44 INFO - { 01:59:44 INFO - "project": "", 01:59:44 INFO - "product": "firefox", 01:59:44 INFO - "script_repo_revision": "production", 01:59:44 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:59:44 INFO - "repository": "", 01:59:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:59:44 INFO - "buildid": "20170206004003", 01:59:44 INFO - "stage_platform": "macosx64", 01:59:44 INFO - "basedir": "/builds/slave/test", 01:59:44 INFO - "nightly_build": "True", 01:59:44 INFO - "buildnumber": 20, 01:59:44 INFO - "slavename": "t-yosemite-r7-0098", 01:59:44 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 01:59:44 INFO - "platform": "yosemite_r7", 01:59:44 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:59:44 INFO - "branch": "mozilla-aurora", 01:59:44 INFO - "repo_path": "releases/mozilla-aurora", 01:59:44 INFO - "revision": "c3d208c04d8b68ed20a0b62c2f9a5bcc83aa9349", 01:59:44 INFO - "pgo_build": "False", 01:59:44 INFO - "builduid": "88237725478d492189fc0c031a7cb528", 01:59:44 INFO - "slavebuilddir": "test" 01:59:44 INFO - } 01:59:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 01:59:44 INFO - Running post-action listener: _resource_record_post_action 01:59:44 INFO - [mozharness: 2017-02-06 09:59:44.601188Z] Finished read-buildbot-config step (success) 01:59:44 INFO - [mozharness: 2017-02-06 09:59:44.601302Z] Running download-and-extract step. 01:59:44 INFO - Running pre-action listener: _resource_record_pre_action 01:59:44 INFO - Running main action method: download_and_extract 01:59:44 INFO - mkdir: /builds/slave/test/build/tests 01:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:59:44 INFO - trying https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 01:59:44 INFO - Downloading https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:59:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 01:59:45 INFO - Downloaded 1199 bytes. 01:59:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 01:59:45 INFO - Using the following test package requirements: 01:59:45 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:59:45 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 01:59:45 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 01:59:45 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'jsshell-mac.zip'], 01:59:45 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 01:59:45 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 01:59:45 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 01:59:45 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 01:59:45 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 01:59:45 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 01:59:45 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 01:59:45 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:59:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:59:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:59:45 INFO - Fetch https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:59:46 INFO - Expected file size: 16356057 01:59:46 INFO - Obtained file size: 16356057 01:59:53 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:59:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 01:59:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:59:53 INFO - Fetch https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 01:59:54 INFO - Expected file size: 16356057 01:59:54 INFO - Obtained file size: 16356057 02:00:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 02:00:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:00:01 INFO - Fetch https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 02:00:02 INFO - Expected file size: 14403485 02:00:02 INFO - Obtained file size: 14403485 02:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:03 INFO - trying https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:00:03 INFO - Downloading https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 02:00:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 02:00:05 INFO - Downloaded 73962161 bytes. 02:00:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:00:05 INFO - mkdir: /builds/slave/test/properties 02:00:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:00:05 INFO - Writing to file /builds/slave/test/properties/build_url 02:00:05 INFO - Contents: 02:00:05 INFO - build_url:https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:00:05 INFO - Running post-action listener: _resource_record_post_action 02:00:05 INFO - Running post-action listener: set_extra_try_arguments 02:00:05 INFO - [mozharness: 2017-02-06 10:00:05.961390Z] Finished download-and-extract step (success) 02:00:05 INFO - [mozharness: 2017-02-06 10:00:05.961499Z] Running populate-webroot step. 02:00:05 INFO - Running pre-action listener: _resource_record_pre_action 02:00:05 INFO - Running main action method: populate_webroot 02:00:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:00:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:00:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:00:05 INFO - '--tppagecycles', 02:00:05 INFO - '7'], 02:00:05 INFO - 'tests': ['tsvgm']}}, 02:00:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:00:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:00:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:00:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:00:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:00:05 INFO - 'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:00:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:00:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:00:05 INFO - 'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['damp', 'tps']}, 02:00:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:00:05 INFO - 'tests': ['damp', 'tps']}, 02:00:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['dromaeo_dom']}, 02:00:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:00:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['basic_compositor_video']}, 02:00:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:00:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['a11yr', 02:00:05 INFO - 'ts_paint', 02:00:05 INFO - 'tpaint', 02:00:05 INFO - 'sessionrestore', 02:00:05 INFO - 'sessionrestore_no_auto_restore', 02:00:05 INFO - 'tabpaint']}, 02:00:05 INFO - 'other-e10s': {'tests': ['a11yr', 02:00:05 INFO - 'ts_paint', 02:00:05 INFO - 'tpaint', 02:00:05 INFO - 'sessionrestore', 02:00:05 INFO - 'sessionrestore_no_auto_restore', 02:00:05 INFO - 'tabpaint']}, 02:00:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:00:05 INFO - 'ts_paint', 02:00:05 INFO - 'tpaint', 02:00:05 INFO - 'sessionrestore', 02:00:05 INFO - 'sessionrestore_no_auto_restore', 02:00:05 INFO - 'tabpaint']}, 02:00:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:00:05 INFO - 'ts_paint', 02:00:05 INFO - 'tpaint', 02:00:05 INFO - 'sessionrestore', 02:00:05 INFO - 'sessionrestore_no_auto_restore', 02:00:05 INFO - 'tabpaint']}, 02:00:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['a11yr', 02:00:05 INFO - 'ts_paint', 02:00:05 INFO - 'tpaint', 02:00:05 INFO - 'sessionrestore', 02:00:05 INFO - 'sessionrestore_no_auto_restore', 02:00:05 INFO - 'tabpaint']}, 02:00:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['a11yr', 02:00:05 INFO - 'ts_paint', 02:00:05 INFO - 'tpaint', 02:00:05 INFO - 'sessionrestore', 02:00:05 INFO - 'sessionrestore_no_auto_restore', 02:00:05 INFO - 'tabpaint']}, 02:00:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['tsvgx', 02:00:05 INFO - 'tsvgr_opacity', 02:00:05 INFO - 'tart', 02:00:05 INFO - 'tscrollx', 02:00:05 INFO - 'cart', 02:00:05 INFO - 'tsvg_static']}, 02:00:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:00:05 INFO - 'tsvgr_opacity', 02:00:05 INFO - 'tart', 02:00:05 INFO - 'tscrollx', 02:00:05 INFO - 'cart', 02:00:05 INFO - 'tsvg_static']}, 02:00:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:00:05 INFO - 'talos_options': ['--disable-e10s'], 02:00:05 INFO - 'tests': ['tp5o']}, 02:00:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:00:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:00:05 INFO - 'talos_options': ['--disable-e10s', 02:00:05 INFO - '--xperf_path', 02:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:00:05 INFO - 'tests': ['tp5n']}, 02:00:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:00:05 INFO - 'talos_options': ['--xperf_path', 02:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:00:05 INFO - 'tests': ['tp5n']}}, 02:00:05 INFO - 'talos.zip': {'path': '', 02:00:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:00:05 INFO - Running post-action listener: _resource_record_post_action 02:00:05 INFO - [mozharness: 2017-02-06 10:00:05.980598Z] Finished populate-webroot step (success) 02:00:05 INFO - [mozharness: 2017-02-06 10:00:05.980713Z] Running create-virtualenv step. 02:00:05 INFO - Running pre-action listener: _resource_record_pre_action 02:00:05 INFO - Running main action method: create_virtualenv 02:00:05 INFO - Creating virtualenv /builds/slave/test/build/venv 02:00:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:00:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:00:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:00:06 INFO - Using real prefix '/tools/python27' 02:00:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:00:06 INFO - Installing distribute.............................................................................................................................................................................................done. 02:00:09 INFO - Installing pip.................done. 02:00:09 INFO - Return code: 0 02:00:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:00:09 INFO - Reading from file tmpfile_stdout 02:00:09 INFO - Output received: 02:00:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:00:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:09 INFO - 'HOME': '/Users/cltbld', 02:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:09 INFO - 'LOGNAME': 'cltbld', 02:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:09 INFO - 'MOZ_NO_REMOTE': '1', 02:00:09 INFO - 'NO_EM_RESTART': '1', 02:00:09 INFO - 'PAGER': '/bin/cat', 02:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:09 INFO - 'PWD': '/builds/slave/test', 02:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:09 INFO - 'SHELL': '/bin/bash', 02:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:09 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:09 INFO - 'USER': 'cltbld', 02:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:09 INFO - 'XPC_FLAGS': '0x0', 02:00:09 INFO - 'XPC_SERVICE_NAME': '0', 02:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:10 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 02:00:10 INFO - Cleaning up... 02:00:10 INFO - Return code: 0 02:00:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:00:10 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')]} 02:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:10 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')]} 02:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:10 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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00:10 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 02:00:10 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 02:00:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:10 INFO - 'HOME': '/Users/cltbld', 02:00:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:10 INFO - 'LOGNAME': 'cltbld', 02:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:10 INFO - 'MOZ_NO_REMOTE': '1', 02:00:10 INFO - 'NO_EM_RESTART': '1', 02:00:10 INFO - 'PAGER': '/bin/cat', 02:00:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:10 INFO - 'PWD': '/builds/slave/test', 02:00:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:10 INFO - 'SHELL': '/bin/bash', 02:00:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:10 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:10 INFO - 'USER': 'cltbld', 02:00:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:10 INFO - 'XPC_FLAGS': '0x0', 02:00:10 INFO - 'XPC_SERVICE_NAME': '0', 02:00:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:10 INFO - Downloading/unpacking psutil>=3.1.1 02:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:00:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:00:14 INFO - Installing collected packages: psutil 02:00:14 INFO - Running setup.py install for psutil 02:00:14 INFO - building 'psutil._psutil_osx' extension 02:00:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:00:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:00:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:00:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:00:14 INFO - building 'psutil._psutil_posix' extension 02:00:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:00:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:00:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:00:14 INFO - ^ 02:00:14 INFO - 1 warning generated. 02:00:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:00:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:00:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:00:14 INFO - Successfully installed psutil 02:00:14 INFO - Cleaning up... 02:00:15 INFO - Return code: 0 02:00:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:00:15 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')]} 02:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:15 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')]} 02:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:15 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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00:15 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 02:00:15 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 02:00:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:15 INFO - 'HOME': '/Users/cltbld', 02:00:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:15 INFO - 'LOGNAME': 'cltbld', 02:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:15 INFO - 'MOZ_NO_REMOTE': '1', 02:00:15 INFO - 'NO_EM_RESTART': '1', 02:00:15 INFO - 'PAGER': '/bin/cat', 02:00:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:15 INFO - 'PWD': '/builds/slave/test', 02:00:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:15 INFO - 'SHELL': '/bin/bash', 02:00:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:15 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:15 INFO - 'USER': 'cltbld', 02:00:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:15 INFO - 'XPC_FLAGS': '0x0', 02:00:15 INFO - 'XPC_SERVICE_NAME': '0', 02:00:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:00:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:00:18 INFO - Installing collected packages: mozsystemmonitor 02:00:18 INFO - Running setup.py install for mozsystemmonitor 02:00:19 INFO - Successfully installed mozsystemmonitor 02:00:19 INFO - Cleaning up... 02:00:19 INFO - Return code: 0 02:00:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:00: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')]} 02:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00: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')]} 02:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00: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', '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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:00: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 jsonschema==2.5.1 02:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:19 INFO - 'HOME': '/Users/cltbld', 02:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:19 INFO - 'LOGNAME': 'cltbld', 02:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:19 INFO - 'MOZ_NO_REMOTE': '1', 02:00:19 INFO - 'NO_EM_RESTART': '1', 02:00:19 INFO - 'PAGER': '/bin/cat', 02:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:19 INFO - 'PWD': '/builds/slave/test', 02:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:19 INFO - 'SHELL': '/bin/bash', 02:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:19 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:19 INFO - 'USER': 'cltbld', 02:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:19 INFO - 'XPC_FLAGS': '0x0', 02:00:19 INFO - 'XPC_SERVICE_NAME': '0', 02:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:19 INFO - Downloading/unpacking jsonschema==2.5.1 02:00: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 02:00: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 02:00: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 02:00: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 02:00: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 02:00: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 02:00:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:00:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:00:21 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 02:00:21 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 02:00:21 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 02:00:21 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 02:00:21 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 02:00:21 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 02:00:22 INFO - Downloading functools32-3.2.3-2.tar.gz 02:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:00:22 INFO - warning: no files found matching '*.txt' 02:00:22 INFO - no previously-included directories found matching 'build' 02:00:22 INFO - no previously-included directories found matching 'dist' 02:00:22 INFO - no previously-included directories found matching '.git*' 02:00:22 INFO - Installing collected packages: jsonschema, functools32 02:00:22 INFO - Running setup.py install for functools32 02:00:22 INFO - warning: no files found matching '*.txt' 02:00:22 INFO - no previously-included directories found matching 'build' 02:00:22 INFO - no previously-included directories found matching 'dist' 02:00:22 INFO - no previously-included directories found matching '.git*' 02:00:22 INFO - Successfully installed jsonschema functools32 02:00:22 INFO - Cleaning up... 02:00:22 INFO - Return code: 0 02:00:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:00: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')]} 02:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00: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')]} 02:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00: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', '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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:00: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 functools32==3.2.3-2 02:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:22 INFO - 'HOME': '/Users/cltbld', 02:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:22 INFO - 'LOGNAME': 'cltbld', 02:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:22 INFO - 'MOZ_NO_REMOTE': '1', 02:00:22 INFO - 'NO_EM_RESTART': '1', 02:00:22 INFO - 'PAGER': '/bin/cat', 02:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:22 INFO - 'PWD': '/builds/slave/test', 02:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:22 INFO - 'SHELL': '/bin/bash', 02:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:22 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:22 INFO - 'USER': 'cltbld', 02:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:22 INFO - 'XPC_FLAGS': '0x0', 02:00:22 INFO - 'XPC_SERVICE_NAME': '0', 02:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:00:22 INFO - Cleaning up... 02:00:22 INFO - Return code: 0 02:00:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:00: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')]} 02:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00: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')]} 02:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00: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', '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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:00: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 blobuploader==1.2.4 02:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:22 INFO - 'HOME': '/Users/cltbld', 02:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:22 INFO - 'LOGNAME': 'cltbld', 02:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:22 INFO - 'MOZ_NO_REMOTE': '1', 02:00:22 INFO - 'NO_EM_RESTART': '1', 02:00:22 INFO - 'PAGER': '/bin/cat', 02:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:22 INFO - 'PWD': '/builds/slave/test', 02:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:22 INFO - 'SHELL': '/bin/bash', 02:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:22 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:22 INFO - 'USER': 'cltbld', 02:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:22 INFO - 'XPC_FLAGS': '0x0', 02:00:22 INFO - 'XPC_SERVICE_NAME': '0', 02:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:23 INFO - Downloading/unpacking blobuploader==1.2.4 02:00: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 02:00: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 02:00: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 02:00: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 02:00: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 02:00: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 02:00:25 INFO - Downloading blobuploader-1.2.4.tar.gz 02:00:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:00:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:00: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 02:00: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 02:00: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 02:00: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 02:00: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 02:00: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 02:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:00:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:00:26 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 02:00:26 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 02:00:26 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 02:00:26 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 02:00:26 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 02:00:26 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 02:00:27 INFO - Downloading docopt-0.6.1.tar.gz 02:00:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:00:27 INFO - Installing collected packages: blobuploader, requests, docopt 02:00:27 INFO - Running setup.py install for blobuploader 02:00:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:00:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:00:27 INFO - Running setup.py install for requests 02:00:27 INFO - Running setup.py install for docopt 02:00:28 INFO - Successfully installed blobuploader requests docopt 02:00:28 INFO - Cleaning up... 02:00:28 INFO - Return code: 0 02:00:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:28 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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00:28 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 02:00:28 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 02:00:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:28 INFO - 'HOME': '/Users/cltbld', 02:00:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:28 INFO - 'LOGNAME': 'cltbld', 02:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:28 INFO - 'MOZ_NO_REMOTE': '1', 02:00:28 INFO - 'NO_EM_RESTART': '1', 02:00:28 INFO - 'PAGER': '/bin/cat', 02:00:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:28 INFO - 'PWD': '/builds/slave/test', 02:00:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:28 INFO - 'SHELL': '/bin/bash', 02:00:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:28 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:28 INFO - 'USER': 'cltbld', 02:00:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:28 INFO - 'XPC_FLAGS': '0x0', 02:00:28 INFO - 'XPC_SERVICE_NAME': '0', 02:00:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:00:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-wXyHM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:00:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Jirk4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:00:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-fcNGUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:00:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-redt8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:00:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-jHUwLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:00:28 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Us3Dvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-c0QcR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ObSgm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-k05N6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-p0I_j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-VxqTzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-tbQ5dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-NcYoLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-OGFOc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:00:29 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-NbCu21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:00:30 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-JnTPJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:00:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:00:30 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-UGSq5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:00:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:00:30 INFO - Running setup.py install for manifestparser 02:00:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:00:30 INFO - Running setup.py install for mozcrash 02:00:30 INFO - Running setup.py install for mozdebug 02:00:30 INFO - Running setup.py install for mozdevice 02:00:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:00:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:00:30 INFO - Running setup.py install for mozfile 02:00:30 INFO - Running setup.py install for mozhttpd 02:00:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Running setup.py install for mozinfo 02:00:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Running setup.py install for mozInstall 02:00:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Running setup.py install for mozleak 02:00:31 INFO - Running setup.py install for mozlog 02:00:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Running setup.py install for moznetwork 02:00:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:00:31 INFO - Running setup.py install for mozprocess 02:00:32 INFO - Running setup.py install for mozprofile 02:00:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:00:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:00:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:00:32 INFO - Running setup.py install for mozrunner 02:00:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:00:32 INFO - Running setup.py install for mozscreenshot 02:00:32 INFO - Running setup.py install for moztest 02:00:32 INFO - Running setup.py install for mozversion 02:00:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:00:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:00:32 INFO - Cleaning up... 02:00:32 INFO - Return code: 0 02:00:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:00:32 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')]} 02:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:32 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')]} 02:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:32 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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00:32 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 02:00:32 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 02:00:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:32 INFO - 'HOME': '/Users/cltbld', 02:00:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:32 INFO - 'LOGNAME': 'cltbld', 02:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:32 INFO - 'MOZ_NO_REMOTE': '1', 02:00:32 INFO - 'NO_EM_RESTART': '1', 02:00:32 INFO - 'PAGER': '/bin/cat', 02:00:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:32 INFO - 'PWD': '/builds/slave/test', 02:00:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:32 INFO - 'SHELL': '/bin/bash', 02:00:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:32 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:32 INFO - 'USER': 'cltbld', 02:00:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:32 INFO - 'XPC_FLAGS': '0x0', 02:00:32 INFO - 'XPC_SERVICE_NAME': '0', 02:00:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-mTsPjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:00:33 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-GPNzJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:00:33 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-azvAQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:00:33 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Ppjcch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-zc_p0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:00:33 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-L4c_3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:00:33 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-huBAWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:00:33 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ej1y8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:00:33 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)) 02:00:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:00:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-CW5Zkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:00:34 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)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-s55nX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:00:34 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)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-QXzvXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:00:34 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)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Zv3gBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-LJ_daX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:00:34 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)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-rFE_LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:00:34 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)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-PrE52Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:00:34 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)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-IpenEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:00:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:00:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-qiNFmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:00:34 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)) 02:00:34 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)) 02:00:34 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)) 02:00:34 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)) 02:00:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:00:34 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:00:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:00:34 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 02:00:34 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 02:00:34 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 02:00:34 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 02:00:34 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 02:00:34 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 02:00:37 INFO - Downloading blessings-1.6.tar.gz 02:00:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:00:37 INFO - Installing collected packages: blessings 02:00:37 INFO - Running setup.py install for blessings 02:00:38 INFO - Successfully installed blessings 02:00:38 INFO - Cleaning up... 02:00:38 INFO - Return code: 0 02:00:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:00:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:00:38 INFO - Reading from file tmpfile_stdout 02:00:38 INFO - Current package versions: 02:00:38 INFO - blessings == 1.6 02:00:38 INFO - blobuploader == 1.2.4 02:00:38 INFO - docopt == 0.6.1 02:00:38 INFO - functools32 == 3.2.3-2 02:00:38 INFO - jsonschema == 2.5.1 02:00:38 INFO - manifestparser == 1.1 02:00:38 INFO - mozInstall == 1.12 02:00:38 INFO - mozcrash == 1.0 02:00:38 INFO - mozdebug == 0.1 02:00:38 INFO - mozdevice == 0.49 02:00:38 INFO - mozfile == 1.2 02:00:38 INFO - mozhttpd == 0.7 02:00:38 INFO - mozinfo == 0.9 02:00:38 INFO - mozleak == 0.1 02:00:38 INFO - mozlog == 3.4 02:00:38 INFO - moznetwork == 0.27 02:00:38 INFO - mozprocess == 0.24 02:00:38 INFO - mozprofile == 0.28 02:00:38 INFO - mozrunner == 6.13 02:00:38 INFO - mozscreenshot == 0.1 02:00:38 INFO - mozsystemmonitor == 0.3 02:00:38 INFO - moztest == 0.8 02:00:38 INFO - mozversion == 1.4 02:00:38 INFO - psutil == 3.1.1 02:00:38 INFO - requests == 1.2.3 02:00:38 INFO - wsgiref == 0.1.2 02:00:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:38 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 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00:38 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 02:00:38 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 02:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:38 INFO - 'HOME': '/Users/cltbld', 02:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:38 INFO - 'LOGNAME': 'cltbld', 02:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:38 INFO - 'MOZ_NO_REMOTE': '1', 02:00:38 INFO - 'NO_EM_RESTART': '1', 02:00:38 INFO - 'PAGER': '/bin/cat', 02:00:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:38 INFO - 'PWD': '/builds/slave/test', 02:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:38 INFO - 'SHELL': '/bin/bash', 02:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:38 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:38 INFO - 'USER': 'cltbld', 02:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:38 INFO - 'XPC_FLAGS': '0x0', 02:00:38 INFO - 'XPC_SERVICE_NAME': '0', 02:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 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)) 02:00:38 INFO - Cleaning up... 02:00:38 INFO - Return code: 0 02:00:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:00:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106196b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106199768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8414879c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, '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.2Zx5VVDhpx/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.OFVAHovodq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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 02:00:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:00:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:00:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:00:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:00:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:00:38 INFO - 'HOME': '/Users/cltbld', 02:00:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:00:38 INFO - 'LOGNAME': 'cltbld', 02:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:00:38 INFO - 'MOZ_NO_REMOTE': '1', 02:00:38 INFO - 'NO_EM_RESTART': '1', 02:00:38 INFO - 'PAGER': '/bin/cat', 02:00:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:00:38 INFO - 'PWD': '/builds/slave/test', 02:00:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:00:38 INFO - 'SHELL': '/bin/bash', 02:00:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:00:38 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:00:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:00:38 INFO - 'USER': 'cltbld', 02:00:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:00:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:00:38 INFO - 'XPC_FLAGS': '0x0', 02:00:38 INFO - 'XPC_SERVICE_NAME': '0', 02:00:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:00:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:00:39 INFO - Cleaning up... 02:00:39 INFO - Return code: 0 02:00:39 INFO - Running post-action listener: _resource_record_post_action 02:00:39 INFO - Running post-action listener: _start_resource_monitoring 02:00:39 INFO - Starting resource monitoring. 02:00:39 INFO - [mozharness: 2017-02-06 10:00:39.073867Z] Finished create-virtualenv step (success) 02:00:39 INFO - [mozharness: 2017-02-06 10:00:39.074838Z] Running install step. 02:00:39 INFO - Running pre-action listener: _resource_record_pre_action 02:00:39 INFO - Running main action method: install 02:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:00:39 INFO - Reading from file tmpfile_stdout 02:00:39 INFO - Detecting whether we're running mozinstall >=1.0... 02:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:00:39 INFO - Reading from file tmpfile_stdout 02:00:39 INFO - Output received: 02:00:39 INFO - Usage: mozinstall [options] installer 02:00:39 INFO - Options: 02:00:39 INFO - -h, --help show this help message and exit 02:00:39 INFO - -d DEST, --destination=DEST 02:00:39 INFO - Directory to install application into. [default: 02:00:39 INFO - "/builds/slave/test"] 02:00:39 INFO - --app=APP Application being installed. [default: firefox] 02:00:39 INFO - mkdir: /builds/slave/test/build/application 02:00:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:00:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:00:59 INFO - Reading from file tmpfile_stdout 02:00:59 INFO - Output received: 02:00:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:00:59 INFO - Running post-action listener: _resource_record_post_action 02:00:59 INFO - [mozharness: 2017-02-06 10:00:59.671503Z] Finished install step (success) 02:00:59 INFO - [mozharness: 2017-02-06 10:00:59.671664Z] Running run-tests step. 02:00:59 INFO - Running pre-action listener: _resource_record_pre_action 02:00:59 INFO - Running main action method: run_tests 02:00:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:00:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:00:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:00:59 INFO - 2017-02-06 02:00:59.685 screenresolution[1910:21260] starting screenresolution argv=screenresolution get 02:00:59 INFO - 2017-02-06 02:00:59.687 screenresolution[1910:21260] Display 0: 1600x1200x32@60 02:00:59 INFO - 2017-02-06 02:00:59.693 screenresolution[1911:21263] starting screenresolution argv=screenresolution list 02:00:59 INFO - Available Modes on Display 0 02:00:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:00:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:00:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:00:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:00:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:00:59 INFO - Intel Iris: 02:00:59 INFO - Chipset Model: Intel Iris 02:00:59 INFO - Type: GPU 02:00:59 INFO - Bus: Built-In 02:00:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:00:59 INFO - Vendor: Intel (0x8086) 02:00:59 INFO - Device ID: 0x0a2e 02:00:59 INFO - Revision ID: 0x0009 02:00:59 INFO - Displays: 02:00:59 INFO - Display: 02:00:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:00:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:00:59 INFO - Display Serial Number: 1600x1200 60 02:00:59 INFO - Main Display: Yes 02:00:59 INFO - Mirror: Off 02:00:59 INFO - Online: Yes 02:00:59 INFO - Rotation: Supported 02:00:59 INFO - Return code: 0 02:00:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:00:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:00:59 INFO - Python 2.7.3 02:00:59 INFO - Return code: 0 02:00:59 INFO - grabbing minidump binary from tooltool 02:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:00:59 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 0x105f74d30>, '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 0x105e9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105f69be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:00:59 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 02:00:59 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 02:00:59 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 02:00:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:01:00 INFO - Return code: 0 02:01:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:01:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:01:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:01:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:01:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:01:00 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/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 02:01:00 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/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --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 02:01:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2Zx5VVDhpx/Render', 02:01:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:01:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:01:00 INFO - 'HOME': '/Users/cltbld', 02:01:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:01:00 INFO - 'LOGNAME': 'cltbld', 02:01:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:01:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:01:00 INFO - 'MOZ_NO_REMOTE': '1', 02:01:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:01:00 INFO - 'NO_EM_RESTART': '1', 02:01:00 INFO - 'PAGER': '/bin/cat', 02:01:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:01:00 INFO - 'PWD': '/builds/slave/test', 02:01:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:01:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:01:00 INFO - 'SHELL': '/bin/bash', 02:01:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OFVAHovodq/Listeners', 02:01:00 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 02:01:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:01:00 INFO - 'USER': 'cltbld', 02:01:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:01:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:01:00 INFO - 'XPC_FLAGS': '0x0', 02:01:00 INFO - 'XPC_SERVICE_NAME': '0', 02:01:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:01:00 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/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--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 02:01:00 INFO - mozversion application_buildid: 20170206004003 02:01:00 INFO - mozversion application_changeset: c3d208c04d8b68ed20a0b62c2f9a5bcc83aa9349 02:01:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:01:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:01:00 INFO - mozversion application_name: Firefox 02:01:00 INFO - mozversion application_remotingname: firefox-dev 02:01:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:01:00 INFO - mozversion application_vendor: Mozilla 02:01:00 INFO - mozversion application_version: 53.0a2 02:01:00 INFO - mozversion platform_buildid: 20170206004003 02:01:00 INFO - mozversion platform_changeset: c3d208c04d8b68ed20a0b62c2f9a5bcc83aa9349 02:01:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:01:00 INFO - mozversion platform_version: 53.0a2 02:01:00 INFO - using testdate: 1486375260 02:01:00 INFO - actual date: 1486375260 02:01:00 INFO - starting webserver on 'localhost:56518' 02:01:00 INFO - SUITE-START | Running 2 tests 02:01:00 INFO - TEST-START | dromaeo_css 02:01:00 INFO - operating with platform_type : mac_ 02:01:00 INFO - Initialising browser for dromaeo_css test... 02:01:00 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp3DVbPk/profile http://localhost:56518/getInfo.html) 02:01:02 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 02:01:02 INFO - PROCESS | 1921 | colorDepth:24 02:01:02 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 02:01:02 INFO - PROCESS | 1921 | __metrics 02:01:02 INFO - PROCESS | 1921 | 1486375262938 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 02:01:02 INFO - PROCESS | 1921 | 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" 02:01:02 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:01:03 INFO - TEST-INFO | 1921: exit 0 02:01:03 INFO - Browser initialized. 02:01:03 INFO - Running cycle 1/1 for dromaeo_css test... 02:01:03 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmp3DVbPk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:01:15 INFO - PROCESS | 1924 | RSS: Main: 213311488 02:01:15 INFO - PROCESS | 1924 | 02:04:19 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56518/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56518/tests/dromaeo/cssquery-ext.html) 02:04:20 INFO - PROCESS | 1924 | RSS: Main: 351117312 02:04:20 INFO - PROCESS | 1924 | 02:06:06 INFO - PROCESS | 1924 | ************************* 02:06:06 INFO - PROCESS | 1924 | A coding exception was thrown and uncaught in a Task. 02:06:06 INFO - PROCESS | 1924 | 02:06:06 ERROR - PROCESS | 1924 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:06:06 INFO - PROCESS | 1924 | Full stack: 02:06:06 INFO - PROCESS | 1924 | ************************* 02:07:23 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56518/tests/dromaeo/cssquery-ext.html (next: http://localhost:56518/tests/dromaeo/cssquery-jquery.html) 02:07:24 INFO - PROCESS | 1924 | RSS: Main: 347013120 02:07:24 INFO - PROCESS | 1924 | 02:10:26 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56518/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56518/tests/dromaeo/cssquery-mootools.html) 02:10:27 INFO - PROCESS | 1924 | RSS: Main: 333971456 02:10:27 INFO - PROCESS | 1924 | 02:13:30 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56518/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56518/tests/dromaeo/cssquery-prototype.html) 02:13:30 INFO - PROCESS | 1924 | RSS: Main: 340877312 02:13:30 INFO - PROCESS | 1924 | 02:16:34 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56518/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56518/tests/dromaeo/cssquery-yui.html) 02:16:34 INFO - PROCESS | 1924 | RSS: Main: 310566912 02:16:34 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56518/tests/dromaeo/cssquery-yui.html (next: http://localhost:56518/tests/dromaeo/cssquery-dojo.html) 02:19:38 INFO - PROCESS | 1924 | RSS: Main: 457056256 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | __start_tp_report 02:19:38 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 02:19:38 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 02:19:38 INFO - PROCESS | 1924 | |i|pagename|runs| 02:19:38 INFO - PROCESS | 1924 | |0;dojo.html;2814.9296267593313;3301.0921996450975;3323.9169020774484;3353.7820041697305;3386.8645254189832;5669.433056694334;5678.43215678432;5704.77180912763;5707.6290041147295;5714.114312281593;5605.635633683808;5606.187102870082;5615.382307946123;5631.521320687736;5721.828345149642;5195.168772996321;5202.115640341132;5203.557697595699;5211.973940130313;5273.288106105665;5181.637285390024;5189.091908915924;5293.285406470116;5302.708351040691;5334;5176.275321454999;5201.453847346031;5208.8437346879655;5218.66078704883;5285.709285989288;5218.373795144588;5262.578993680497;5265.341832270966;5276.12943864262;5276.920846187291;5150.61370397218;5205.583553315763;5222.477752224785;5246.994736137114;5269.552088072518;5539.944600553983;5587.469190426903;5624.156376543551;5628.887422251573;5681.119426488901;6133.763142530635;6149.41580549847;6183.505319574423;6184.752609895599;6193.411625895533;6155.784547540859;6228.096925945758;6236.6258024518675;6241.407066328691;6244.500439964792;5629.887402251932;5741.799037033684;5748.8850222995725;5757.366689664133;5788.218590490272;6180.351063138396;6226.470749986302;6238.875222495525;6239.469645080219;6247.500199983991;6071.574989750675;6071.696415179224;6085.513158947274;6086.908696369558;6099.634021958608;6167.753289868355;6196.1635179251125;6213.254409470893;6239.939210334254;6250.718717657716;6089.269287685506;6106.043442834774;6121.971262793606;6128.014278093809;6186.350433204449;6247.875042499216;6260.874782504325;6261.467775239064;6264.404881536338;6265.8120256392385;4955.554000140005;4996.125678006334;5007.599392048627;5015.623828212899;5028.622853286018;4350.673699472489;4395.164918665443;4401.6698747594055;4429.977850110793;4466.508684044752;5754.597178197486;5766.942330576724;5771.509421699203;5777.162311464898;5779.595428319977;5726.685032323262;6075.088736689444;6250.3749625037135;6266.404691554295;6296.937030629727;5765.221695071195;5821.708914554255;5830.5044071254415;5843.152742852262;5858.326292476335;5285.577153827761;5451.509364157166;5457.890842183134;5458.590605704509;5460.590455715837;6297.149884765498;6304.18045654062;6307.400796924285;6316.05259211122;6352.237731472161;6303.558750887394;6324.747010119728;6334.746610135543;6335.619862808247;6352.809415717536;6274.874502510015;6323.7786677466065;6334.714937827901;6347.90478142819;6350.491960643045;6070.817875463743;6131.294901086432;6138.109974053827;6147.784827531015;6181.629102253879;4777.665563410667;6187.257529096357;6275.811725648238;6308.810735677937;6312.5265605078885;6055.061465472859;6115.785947491817;6118.969405152946;6124.14262003311;6125.908111378244;5759.164921086335;5864.736086876015;5880.705964701833;5914.58597898143;5980.102984552352;5866.6480011199465;5919.437653422918;5938.881222375442;5939.406059394199;5968.8806223876145;6047.455728984412;6057.273127224585;6076.1493390924425;6076.513878889767;6099.2985806632805;5754.223179870832;5801.100829371454;5816.47651711348;5848.444397782204;5904.084866845646 02:19:38 INFO - PROCESS | 1924 | |1;ext.html;460.53255945215614;475.44135640622267;480.73319307784163;508.6769901112792;516.9844904652861;30509.542356864637;32461.350772984526;41873.371899421494;42553.57446425535;42736;42932.35601465973;43041.56958430415;43152.784236078856;43156.568434315726;43245.702628921106;9233.67682131124;9459.905400946005;9463.337566370323;9476.289278304155;9481.62073517062;8471.957640211791;8678.262347700453;9571.856422153673;11253.381064041474;11469.655910322703;11393.658190254264;11457.828132578017;11459.59891403801;11483.368414737186;11499.3100413975;11345.205835591512;11406.08751299894;11422.14333924959;11438;11447.59933402331;8412;8621.396502244845;10979.1765617579;11264;11301.03941165002;40732.38901416481;40737.38893916564;41224;41305;41375;3458.913527161816;3986.1828325193264;4049.7165198436123;4080.8571699990357;4087.734297270668;22958.96684649195;28753.281167970967;28935.71064289351;29199.124026279245;29220.685069171937;3236.579244698174;3269.591301087364;3347.0126312737802;3349.1794510344876;3359.1937934895695;468904;469187.6540617309;470911;471834;472956.6352168252;51552.22671659928;62321;62462.68768656128;62739;62839.6858015707;64308;64364.35635643677;64481.67759161174;64493;64515;13636.795448068287;16758.1620918956;16774.245158967875;16780.909240899302;16791.91604041972;11817.704557386134;12454.750904981849;12498.875011249953;12565.434555444845;12571.748565028649;14626.268686565629;14628.926855365655;16093.907791986967;16102.03387796736;16337.918310408371;6856.828579285458;7007.8598428031155;7055.541389809646;7060.046893669289;7071.504994650428;749.9512531685532;867.5835598912431;894.6779159502574;916.7341470973477;927.9953600232091;28833.423331533253;29862.398848408935;29977;30105.548416773767;30106.247343816143;1043.0925095167254;1076.7308172956762;1077.256692881909;1089.929154604948;1108.1854836695022;15195.848041519665;15624.453144139901;15684.529464116094;15731.685366292839;16003.359865605245;611515;615907;616351;618704;619496;61364.38635613676;61606.69196653988;61678.691606542576;61834.38165618376;61900.76198476005;1438.0699742604077;1454.3600815641084;1456.7450696128135;1480.970380592382;1486.4500134949922;24399.75600244046;28021.299467513476;28941.85529072341;29562.852185738935;29628;50366;50668;50782;50848.74575627098;50931;523662;527870;528457;528467;529157;25972.220833375028;26291.07981220648;26457.867710661325;26537.07120250782;26542.734572654797;34395.45220465035;39627;44602.77698611616;47714.04571908612;49931.75170620764;38622.6137738619;38869.6113038866;39720.20559588734;40716;40957.38563921603;38873.80563097166;39221.803890980365;39260.214795704494;40354.39468408035;40644.39033414442;35363.823180883934;36925.446118308784;36948.630513694516;37679.81160094182;38307.425388618634;26012.739872601032;30996.380072397973;32336.514952276204;32384.676153238164;32560;24321;24443.51112977695;24617.876910615334;24912.26316052534;25078 02:19:38 INFO - PROCESS | 1924 | |2;jquery.html;390.2351391273106;394.4950463406839;397.5527531527032;401.99799001005;421.00642483738375;19206.80793192071;20609.86645734484;21053.894730526325;21905.123795048217;21905.67141492877;21343.893280533615;21836.781632183673;22269.554608907813;22508.662370064463;22779.77220227797;5814.912776308359;5993.8801223975715;6156.1996940397685;6163.568550201488;6169.352218017111;5699.857503562403;5936.703164841762;5982.820515384546;6001.06983417571;6115.113308570277;4975.4527002029745;5955.076963070731;5957.910631340534;5976.820695379146;5982.282126144869;5706.971465142668;5889.764409423639;5944.405559444042;5954.612950158225;5972.044472884318;5274.868128296785;5277.6305658603915;5278.313819203479;5294.364676238837;5304.496072873071;26083.347916302246;26839.46321073587;26892.327691807863;26954.730452695418;27206.31984200391;2790.079273839628;2795.622590950216;2816.7887408444244;2820.9294767630972;2835.1636267301196;13505.514393416715;15353.232338383035;15807.288672009787;16251.349946002145;16688.58278543034;1849.2695385322857;1873.6721073812164;1996.2713609532416;1997;2010.8793472391558;98092;104862.47568762107;107777.46111269471;107847;147547;11514.251573647685;11862.584809531625;12121.39393030345;12144.635660930186;12239.449224784907;16062.518124456285;16100.919495402448;16328.346866125223;16346.34614615426;16380.26288817006;7900.723474678473;8291.419600628018;8315.501069935823;8558.786030349167;8714.607842646968;6509.609423434515;8629.43908645936;8747.825043499095;8750.737477875675;8896.199342059117;4885.291632713308;4901.583365413909;4905.362302900671;4916.631252656065;4978.4025916890205;1969.5371587676884;2014.8589598728242;2022.5853699991467;2024.5444774925818;2027.9188832446832;849.8555245608261;901.8241442918568;901.9458832470073;920.995395023034;933.1321870660352;21844.78155218459;22840.771592284196;22876.542469150525;22881.427964301027;22883.542329153322;913.9131782480787;915.272358475014;917.6788124156625;932.1517419148543;934.2525979216599;8950.910490895008;10141;11647.41762911851;11657.358845263423;11783.234089784137;650099.7495012495;727028.3648581724;740775;751780;764985;115553.4222328883;135060.9740853888;148859.76710349353;150145;160477;856.7087190355309;1068.3643232276784;1074.8226542620537;1079.9298045627008;1084.7017070305571;19856.801431985496;20963.58072838504;21348.573028539653;21443.142274309477;21461;53340;53990.920181596935;58150.12774808297;62332.376676232656;62642.686786567596;93422;93509;95092;97474.12629368361;97565;7352.926470735224;7975.521468711896;8585.82828343442;8653.004904435818;8844.513551754715;10996.615118470816;12780;13740.725185496434;13839.930800345934;13965.790513142514;11362.886371136183;11646.88353116458;11785.116116291483;11982.281063136239;11983.400829958293;10632.468376581295;11514.136439766884;11537.307761534335;11816.822747658964;11869.347185904859;9944.502774861083;10688.679339619825;11679.124065694938;11843.526258949545;11872.76254474923;8546.871796922927;8572.78568035779;8588.398812083095;8743.562821858754;8877.201051905102;9068.909310906807;11504.482298296763;11646.417679116179;11763;11970 02:19:38 INFO - PROCESS | 1924 | |3;mootools.html;1387.3618135657598;1431.870213941739;1450.7098580283946;1475.0412232049173;1476.1217075839875;8103.635336409861;8529.445586036903;8530.061693213742;8554.315654747614;8564.443311184768;7407.444441666883;7712.4215683823795;7718.768436946886;7983.241592048761;8208.425410221287;2300.3329034579992;2339.7426283108844;2341.4263505441104;2344.0623750499767;2348.5068135691527;2320.14154762738;2342.390978345625;2355.893984770681;2364.373441038126;2369.786719195272;2309.364924645719;2317.7450480447137;2342.6251799711963;2347.366211122996;2349.259983105325;2251.4146321956327;2271.670607761882;2282.44080200352;2320.6983092198075;2323.1056043423237;2288.187693368863;2352.270796053229;2353.0234952494693;2359.596040355985;2361.9173328933407;8391;8446.155384461566;8462.15378462155;8470.110638383005;8484;5059.3169922060415;5235.0576896159055;5243.659162154448;5244.60665450089;5249.42256351801;3021.6676165621766;3242.7243684286864;3254.2840575073446;3474.2356681530277;3498.422760244582;1042.5412818359898;1211.497228650109;1218.8293638890473;1242.1180961517334;1264.9494020239179;10659.840102398548;10759.946200269027;10845.295055821347;10870.673879783619;10882.673519794418;4472.038511719931;4684.320773487825;4750.667453278237;4764.595009424238;4769.6661233714;9297.442153470813;9493.05069493045;9546.713598592054;9558;9568.282378821616;5282.867928301746;5284.392294886137;5297.152455607162;5300.549453296436;5301.761420736076;4243.875373026151;4299.871003869889;4354.586314300136;4814.277858321279;4863.392075990501;3894.5521265054313;3926.371780515104;4008.9198216035516;4020.3567429211184;4036.5156181258435;1107.565702415358;1280.7950727883494;1282.3139620303093;1288.916220445668;1296.3129541343103;800.1798156889118;807.4953154278576;808.401782680812;818.0061225610912;828.3598475019495;4587.655925805578;4893.584045356113;5346.465353464622;5628.915566266509;5673.659580425187;556.5297323761415;575.4964406144624;578.17032558279;596.785157343356;598.6709504899117;3098.806959320633;3127.4214270360058;3777.1690228149764;3815.1797363566966;3894.162755007687;9705.854412183822;9886.80226395468;10047.09576138151;10222.437765922945;10223.335483193569;9799.216062715108;9896.307258491837;9932.602695892083;9973;10029.64896228643;7818.921810781933;7822.647980840876;7865.646045927947;7877.212278772192;7999.200079991954;5458;5481.917771233355;5585.581081418991;5665.206871037975;5671.461211184931;9245.445273283625;9336.439813611205;9423;9436.292278079034;9472.242220622471;9066.818663626822;9386.108319709616;9422.811543769223;9451.669191578261;9541.998090200428;6611.702473388806;6815.318468153145;6815.454763618998;7121.252268511732;7252.5648461092505;7634.809129771578;7793.9220607793195;7910.920890791019;8004.719834805754;8019.6391162398995;7034.5427547209265;7115.359617634438;7122.679479423542;7161.606111663908;7221.205667376479;6825;6965.357324124366;7016.298370162943;7028.226895041644;7142.678579464041;6397.584157029778;6402.935970640234;6505.2518960318275;6514;6516.152900122954;5000.049990501793;5022.397312322399;5053.115704751727;5065.417476990193;5068.771905264346;4916.434610019894;4950.673183603701;5049.899002020013;5076.746162691777;5096.847094587169 02:19:38 INFO - PROCESS | 1924 | |4;prototype.html;346.833519910443;349.09584176981616;358.43546414397315;370.28165359203126;370.78679759138504;4564.908701825966;5082.03441346144;5133.204353325236;5153.7680804363845;5232.529072383494;4587.908241835161;4769.713817170972;5067.037262920043;5102.719350435725;5147.536721695056;3787.924241515168;4061.2689715851134;4064.7764372959477;4073.796310184494;4077.8980525486804;3484.4773284007474;3959.762414255154;3963.821628026731;4041.2119636670714;4062.4109504121884;3012.7891047626676;3338.532605435241;3350.698437140657;4023;4051.169510250392;3500.03748969033;3766.5856755756845;3884.961150388488;4023.772749311459;4085.916182746791;2692.784577233836;3496.5629296337956;3506.1935754776546;3539.1682954505673;3580.695640870529;5066.290719299301;5112.769925353368;5125.103711626722;5153.613478989091;5209.947900520984;4622.329762184464;4624.28323609841;4634.768261586941;4682.08699303636;4747.952520474785;4958.975205123951;5120.745417372767;5217.921731174036;5462.781488740446;5478.287822583039;3963.5243770747693;3970.9007274818073;3992.6606238469767;4009.599040095967;4009.959900401017;4824.445188803263;5421.810236641738;5608.887822243532;5652.63257888236;5729.312482502085;3333.8999830005137;3416.692497675221;3888.8444462221196;4052.615001574846;4056.3307054335705;3219.661935496786;3541.7166626669805;3966.5835087315995;4085.97957010219;4166.854115118306;4066.9796651017145;4393.253146965024;4429.2027435062;4498.820047198075;4663.323818046364;5063.8987220255385;5447.291852059207;5463.480969307989;5470.64440811346;5492.423295553989;1230.9384530773425;1240.6154092231257;1317.6494093553993;1334.0994828490923;1335.4124185358412;755.029786724057;772.6407220642428;797.0236460336042;828.2918105020121;936.9786932243887;4133.958660413357;4633.467151277646;4717.669763116549;4777.407178094208;4859.562639362474;5232.764525596357;5323.600729945269;5324.361076670824;5354.152817278033;5372.8656783579945;4014.7189696721534;4453.55464453552;4717.8348757794;4843.515648435128;4854.490278520775;2535.3773584905307;2775.791815613837;2787.2161816437497;2792.1571762636563;2794.4690508803264;5174.730847051198;5183;5451.127819548853;5523.364813046551;5545.528630066489;4694.6009589185305;5200;5386.542143917733;5595.916061259084;5733.054046082433;4918.827841025547;5491.65454463651;5744.971275143681;5818.2436283282905;5884.411558844081;5301.469853014668;5374.570034397317;5661.320641523043;5679.1258884568815;5693.4875861172695;4806.663533552758;5186.870328241824;5586.776528938797;5601.411851755507;5612.354579223278;4807.807687692453;5507.889842203213;5535.280413546374;5641.689707066151;5687.829365119053;3357.7145942595153;3793.2982398256413;3802.4106263529197;3804.0599294360627;3848.8075596220638;5223.753785872112;5665.3201615806365;5688.8382414682155;5694.690221249053;5707.800226992035;4772.403449568804;5317.335333083364;5463.159157238293;5491.258680078298;5514.862128446661;4801.375821143229;5332.280536926273;5359.249705041375;5378.435264297349;5389.487998640123;4169.520505141826;5300.575953923754;5327.600429967845;5360.597955153301;5400.891982160413;5388.919166212431;5437.972810135924;5455.0815313806925;5481.287432633732;5493.478119578634;4233.322668373045;5130.51260130287;5247.737613119405;5367.584586935816;5367.704776237344 02:19:38 INFO - PROCESS | 1924 | |5;yui.html;712.8930660400939;725.0574253470487;775.7323723315458;805.7743831727119;810.3638643664724;15435.536933891986;15828.76256856148;15978.201089945514;16030.278637461313;16031.358745650175;14431.422743090263;14558.927205363985;14614.561563153096;14617;14667.193304368255;674.2751542092246;694.2987582541645;697.6476879176034;842.3261390887296;854.2013217641525;844.3456321350941;867.4578388507183;870.4820631724145;871.5859966515918;873.0919843362894;928.2805825485235;929.3866048408051;929.3866048408051;929.8837645294338;930.3812964378681;717.1716667249283;921.3964853021282;922.3082687984012;923.0630909626667;923.2752289452748;544.0289083985068;657.7927952694886;673.2526895146433;673.9056532085511;684.1379861374704;14930.552083437515;14938.32777525014;14970.17664028478;15209.695806083817;15437.614059648598;13931.721365572734;13947.232902190375;13965.650858728613;14002.019858609901;14034.157950523002;12434.81347779784;12523.43644535989;12655.493780248778;12828.230306181658;12984.740305193938;3549.6095429502734;3678.0988657779017;3712.981435092834;3842.1931394407206;3844.519435070616;109230;109852;110042.44978775135;110466.44766776195;111621.44189279001;8980.595873185723;10631.893681063246;10636.989485998818;10828.78342433147;11090.889091109148;21344.893275533523;22086;22098.558028839332;22637.660435093487;22709.318720438412;7827.178146294671;7947.721829736047;7951.761447156595;7961.681532738626;8149.388795840336;4271.273883439822;4316.69783115179;4326;4390.341448782644;4513.187626227311;3565.180008598037;3607.6933460656137;3620.094976255936;3626.9300556335943;3657.6708096271464;564.6950646650845;654.751194546079;658.1509852290587;662.2582707367683;672.9730810767612;684.4968947823346;684.6456536428312;700.7547358424613;717.1072015340934;724.8948902409121;10316.948415257875;10415.59697512002;10431.16550675944;10507.10689591378;10526.52630631608;692.200508412774;716.4304378019488;721.7257442171941;723.4682508356353;726.9018682477798;4778.37881075458;5071.137906555894;5076.162433198555;5111.616628752859;5120.18077107661;97520.09919603278;101925;102736.97263027274;103103;105973.94026059796;91777.54111229401;92254;92826;93824;94986;55078.72460637671;64238;69895.55365562181;79109.20890791134;80886;9704.70885873425;9888.555015024485;9911.355761875455;9919.900800991898;9935;48529.514704852496;52869.47130528645;53441.198382025075;54486.18270725863;54508.72745636247;57646;61358.69320653368;64726.352736472036;69436.65281673559;70537.29462705512;12834.486620535074;13432.059755817;13437.126586771828;13625.727485450434;13866;14002.859971400156;14114.16203708341;14371.922105842314;15058.774118388452;15675.137867417401;13369.197848129143;13555.525556605471;13860.92551348676;14022.929885350508;14489.565313040626;13436.731265374443;13706.65733356674;14089.223418398407;14233.857661423668;14538.273086345853;13484;13671.453141874214;13803.2408217549;13804.03371763967;13805.654858628615;10570.365778053034;10613.357114546052;11653;11681.874844178508;12051.75896482083;10133.661206520259;10667.679969600924;11453.770924581628;11683.707907302385;11727.941360293486 02:19:38 INFO - PROCESS | 1924 | __end_tp_report 02:19:38 INFO - PROCESS | 1924 | __start_cc_report 02:19:38 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,1350 02:19:38 INFO - PROCESS | 1924 | __end_cc_report 02:19:38 INFO - PROCESS | 1924 | __startTimestamp1486376378489__endTimestamp 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | ------- Summary: start ------- 02:19:38 INFO - PROCESS | 1924 | Number of tests: 6 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | [#0] dojo.html Cycles:165 Average:5721.98 Median:5864.74 stddev:643.51 (11.0%) stddev-sans-first:603.73 02:19:38 INFO - PROCESS | 1924 | Values: 2814.9 3301.1 3323.9 3353.8 3386.9 5669.4 5678.4 5704.8 5707.6 5714.1 5605.6 5606.2 5615.4 5631.5 5721.8 5195.2 5202.1 5203.6 5212.0 5273.3 5181.6 5189.1 5293.3 5302.7 5334.0 5176.3 5201.5 5208.8 5218.7 5285.7 5218.4 5262.6 5265.3 5276.1 5276.9 5150.6 5205.6 5222.5 5247.0 5269.6 5539.9 5587.5 5624.2 5628.9 5681.1 6133.8 6149.4 6183.5 6184.8 6193.4 6155.8 6228.1 6236.6 6241.4 6244.5 5629.9 5741.8 5748.9 5757.4 5788.2 6180.4 6226.5 6238.9 6239.5 6247.5 6071.6 6071.7 6085.5 6086.9 6099.6 6167.8 6196.2 6213.3 6239.9 6250.7 6089.3 6106.0 6122.0 6128.0 6186.4 6247.9 6260.9 6261.5 6264.4 6265.8 4955.6 4996.1 5007.6 5015.6 5028.6 4350.7 4395.2 4401.7 4430.0 4466.5 5754.6 5766.9 5771.5 5777.2 5779.6 5726.7 6075.1 6250.4 6266.4 6296.9 5765.2 5821.7 5830.5 5843.2 5858.3 5285.6 5451.5 5457.9 5458.6 5460.6 6297.1 6304.2 6307.4 6316.1 6352.2 6303.6 6324.7 6334.7 6335.6 6352.8 6274.9 6323.8 6334.7 6347.9 6350.5 6070.8 6131.3 6138.1 6147.8 6181.6 4777.7 6187.3 6275.8 6308.8 6312.5 6055.1 6115.8 6119.0 6124.1 6125.9 5759.2 5864.7 5880.7 5914.6 5980.1 5866.6 5919.4 5938.9 5939.4 5968.9 6047.5 6057.3 6076.1 6076.5 6099.3 5754.2 5801.1 5816.5 5848.4 5904.1 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | [#1] ext.html Cycles:180 Average:67545.21 Median:38871.71 stddev:144499.94 (371.7%) stddev-sans-first:144817.52 02:19:38 INFO - PROCESS | 1924 | Values: 460.5 475.4 480.7 508.7 517.0 30509.5 32461.4 41873.4 42553.6 42736.0 42932.4 43041.6 43152.8 43156.6 43245.7 9233.7 9459.9 9463.3 9476.3 9481.6 8472.0 8678.3 9571.9 11253.4 11469.7 11393.7 11457.8 11459.6 11483.4 11499.3 11345.2 11406.1 11422.1 11438.0 11447.6 8412.0 8621.4 10979.2 11264.0 11301.0 40732.4 40737.4 41224.0 41305.0 41375.0 3458.9 3986.2 4049.7 4080.9 4087.7 22959.0 28753.3 28935.7 29199.1 29220.7 3236.6 3269.6 3347.0 3349.2 3359.2 468904.0 469187.7 470911.0 471834.0 472956.6 51552.2 62321.0 62462.7 62739.0 62839.7 64308.0 64364.4 64481.7 64493.0 64515.0 13636.8 16758.2 16774.2 16780.9 16791.9 11817.7 12454.8 12498.9 12565.4 12571.7 14626.3 14628.9 16093.9 16102.0 16337.9 6856.8 7007.9 7055.5 7060.0 7071.5 750.0 867.6 894.7 916.7 928.0 28833.4 29862.4 29977.0 30105.5 30106.2 1043.1 1076.7 1077.3 1089.9 1108.2 15195.8 15624.5 15684.5 15731.7 16003.4 611515.0 615907.0 616351.0 618704.0 619496.0 61364.4 61606.7 61678.7 61834.4 61900.8 1438.1 1454.4 1456.7 1481.0 1486.5 24399.8 28021.3 28941.9 29562.9 29628.0 50366.0 50668.0 50782.0 50848.7 50931.0 523662.0 527870.0 528457.0 528467.0 529157.0 25972.2 26291.1 26457.9 26537.1 26542.7 34395.5 39627.0 44602.8 47714.0 49931.8 38622.6 38869.6 39720.2 40716.0 40957.4 38873.8 39221.8 39260.2 40354.4 40644.4 35363.8 36925.4 36948.6 37679.8 38307.4 26012.7 30996.4 32336.5 32384.7 32560.0 24321.0 24443.5 24617.9 24912.3 25078.0 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | [#2] jquery.html Cycles:180 Average:40000.47 Median:2792.85 stddev:120874.24 (4328.0%) stddev-sans-first:121176.73 02:19:38 INFO - PROCESS | 1924 | Values: 390.2 394.5 397.6 402.0 421.0 19206.8 20609.9 21053.9 21905.1 21905.7 21343.9 21836.8 22269.6 22508.7 22779.8 5814.9 5993.9 6156.2 6163.6 6169.4 5699.9 5936.7 5982.8 6001.1 6115.1 4975.5 5955.1 5957.9 5976.8 5982.3 5707.0 5889.8 5944.4 5954.6 5972.0 5274.9 5277.6 5278.3 5294.4 5304.5 26083.3 26839.5 26892.3 26954.7 27206.3 2790.1 2795.6 2816.8 2820.9 2835.2 13505.5 15353.2 15807.3 16251.3 16688.6 1849.3 1873.7 1996.3 1997.0 2010.9 98092.0 104862.5 107777.5 107847.0 147547.0 11514.3 11862.6 12121.4 12144.6 12239.4 16062.5 16100.9 16328.3 16346.3 16380.3 7900.7 8291.4 8315.5 8558.8 8714.6 6509.6 8629.4 8747.8 8750.7 8896.2 4885.3 4901.6 4905.4 4916.6 4978.4 1969.5 2014.9 2022.6 2024.5 2027.9 849.9 901.8 901.9 921.0 933.1 21844.8 22840.8 22876.5 22881.4 22883.5 913.9 915.3 917.7 932.2 934.3 8950.9 10141.0 11647.4 11657.4 11783.2 650099.7 727028.4 740775.0 751780.0 764985.0 115553.4 135061.0 148859.8 150145.0 160477.0 856.7 1068.4 1074.8 1079.9 1084.7 19856.8 20963.6 21348.6 21443.1 21461.0 53340.0 53990.9 58150.1 62332.4 62642.7 93422.0 93509.0 95092.0 97474.1 97565.0 7352.9 7975.5 8585.8 8653.0 8844.5 10996.6 12780.0 13740.7 13839.9 13965.8 11362.9 11646.9 11785.1 11982.3 11983.4 10632.5 11514.1 11537.3 11816.8 11869.3 9944.5 10688.7 11679.1 11843.5 11872.8 8546.9 8572.8 8588.4 8743.6 8877.2 9068.9 11504.5 11646.4 11763.0 11970.0 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | [#3] mootools.html Cycles:180 Average:5424.79 Median:5266.15 stddev:2986.51 (56.7%) stddev-sans-first:2979.47 02:19:38 INFO - PROCESS | 1924 | Values: 1387.4 1431.9 1450.7 1475.0 1476.1 8103.6 8529.4 8530.1 8554.3 8564.4 7407.4 7712.4 7718.8 7983.2 8208.4 2300.3 2339.7 2341.4 2344.1 2348.5 2320.1 2342.4 2355.9 2364.4 2369.8 2309.4 2317.7 2342.6 2347.4 2349.3 2251.4 2271.7 2282.4 2320.7 2323.1 2288.2 2352.3 2353.0 2359.6 2361.9 8391.0 8446.2 8462.2 8470.1 8484.0 5059.3 5235.1 5243.7 5244.6 5249.4 3021.7 3242.7 3254.3 3474.2 3498.4 1042.5 1211.5 1218.8 1242.1 1264.9 10659.8 10759.9 10845.3 10870.7 10882.7 4472.0 4684.3 4750.7 4764.6 4769.7 9297.4 9493.1 9546.7 9558.0 9568.3 5282.9 5284.4 5297.2 5300.5 5301.8 4243.9 4299.9 4354.6 4814.3 4863.4 3894.6 3926.4 4008.9 4020.4 4036.5 1107.6 1280.8 1282.3 1288.9 1296.3 800.2 807.5 808.4 818.0 828.4 4587.7 4893.6 5346.5 5628.9 5673.7 556.5 575.5 578.2 596.8 598.7 3098.8 3127.4 3777.2 3815.2 3894.2 9705.9 9886.8 10047.1 10222.4 10223.3 9799.2 9896.3 9932.6 9973.0 10029.6 7818.9 7822.6 7865.6 7877.2 7999.2 5458.0 5481.9 5585.6 5665.2 5671.5 9245.4 9336.4 9423.0 9436.3 9472.2 9066.8 9386.1 9422.8 9451.7 9542.0 6611.7 6815.3 6815.5 7121.3 7252.6 7634.8 7793.9 7910.9 8004.7 8019.6 7034.5 7115.4 7122.7 7161.6 7221.2 6825.0 6965.4 7016.3 7028.2 7142.7 6397.6 6402.9 6505.3 6514.0 6516.2 5000.0 5022.4 5053.1 5065.4 5068.8 4916.4 4950.7 5049.9 5076.7 5096.8 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | [#4] prototype.html Cycles:180 Average:4397.97 Median:4889.20 stddev:1339.79 (27.4%) stddev-sans-first:1308.59 02:19:38 INFO - PROCESS | 1924 | Values: 346.8 349.1 358.4 370.3 370.8 4564.9 5082.0 5133.2 5153.8 5232.5 4587.9 4769.7 5067.0 5102.7 5147.5 3787.9 4061.3 4064.8 4073.8 4077.9 3484.5 3959.8 3963.8 4041.2 4062.4 3012.8 3338.5 3350.7 4023.0 4051.2 3500.0 3766.6 3885.0 4023.8 4085.9 2692.8 3496.6 3506.2 3539.2 3580.7 5066.3 5112.8 5125.1 5153.6 5209.9 4622.3 4624.3 4634.8 4682.1 4748.0 4959.0 5120.7 5217.9 5462.8 5478.3 3963.5 3970.9 3992.7 4009.6 4010.0 4824.4 5421.8 5608.9 5652.6 5729.3 3333.9 3416.7 3888.8 4052.6 4056.3 3219.7 3541.7 3966.6 4086.0 4166.9 4067.0 4393.3 4429.2 4498.8 4663.3 5063.9 5447.3 5463.5 5470.6 5492.4 1230.9 1240.6 1317.6 1334.1 1335.4 755.0 772.6 797.0 828.3 937.0 4134.0 4633.5 4717.7 4777.4 4859.6 5232.8 5323.6 5324.4 5354.2 5372.9 4014.7 4453.6 4717.8 4843.5 4854.5 2535.4 2775.8 2787.2 2792.2 2794.5 5174.7 5183.0 5451.1 5523.4 5545.5 4694.6 5200.0 5386.5 5595.9 5733.1 4918.8 5491.7 5745.0 5818.2 5884.4 5301.5 5374.6 5661.3 5679.1 5693.5 4806.7 5186.9 5586.8 5601.4 5612.4 4807.8 5507.9 5535.3 5641.7 5687.8 3357.7 3793.3 3802.4 3804.1 3848.8 5223.8 5665.3 5688.8 5694.7 5707.8 4772.4 5317.3 5463.2 5491.3 5514.9 4801.4 5332.3 5359.2 5378.4 5389.5 4169.5 5300.6 5327.6 5360.6 5400.9 5388.9 5438.0 5455.1 5481.3 5493.5 4233.3 5130.5 5247.7 5367.6 5367.7 02:19:38 INFO - PROCESS | 1924 | 02:19:38 INFO - PROCESS | 1924 | [#5] yui.html Cycles:180 Average:20605.74 Median:3777.59 stddev:29521.95 (781.5%) stddev-sans-first:29566.98 02:19:38 INFO - PROCESS | 1924 | Values: 712.9 725.1 775.7 805.8 810.4 15435.5 15828.8 15978.2 16030.3 16031.4 14431.4 14558.9 14614.6 14617.0 14667.2 674.3 694.3 697.6 842.3 854.2 844.3 867.5 870.5 871.6 873.1 928.3 929.4 929.4 929.9 930.4 717.2 921.4 922.3 923.1 923.3 544.0 657.8 673.3 673.9 684.1 14930.6 14938.3 14970.2 15209.7 15437.6 13931.7 13947.2 13965.7 14002.0 14034.2 12434.8 12523.4 12655.5 12828.2 12984.7 3549.6 3678.1 3713.0 3842.2 3844.5 109230.0 109852.0 110042.4 110466.4 111621.4 8980.6 10631.9 10637.0 10828.8 11090.9 21344.9 22086.0 22098.6 22637.7 22709.3 7827.2 7947.7 7951.8 7961.7 8149.4 4271.3 4316.7 4326.0 4390.3 4513.2 3565.2 3607.7 3620.1 3626.9 3657.7 564.7 654.8 658.2 662.3 673.0 684.5 684.6 700.8 717.1 724.9 10316.9 10415.6 10431.2 10507.1 10526.5 692.2 716.4 721.7 723.5 726.9 4778.4 5071.1 5076.2 5111.6 5120.2 97520.1 101925.0 102737.0 103103.0 105973.9 91777.5 92254.0 92826.0 93824.0 94986.0 55078.7 64238.0 69895.6 79109.2 80886.0 9704.7 9888.6 9911.4 9919.9 9935.0 48529.5 52869.5 53441.2 54486.2 54508.7 57646.0 61358.7 64726.4 69436.7 70537.3 12834.5 13432.1 13437.1 13625.7 13866.0 14002.9 14114.2 14371.9 15058.8 15675.1 13369.2 13555.5 13860.9 14022.9 14489.6 13436.7 13706.7 14089.2 14233.9 14538.3 13484.0 13671.5 13803.2 13804.0 13805.7 10570.4 10613.4 11653.0 11681.9 12051.8 10133.7 10667.7 11453.8 11683.7 11727.9 02:19:38 INFO - PROCESS | 1924 | -------- Summary: end -------- 02:19:38 INFO - PROCESS | 1924 | 02:19:39 INFO - TEST-INFO | 1924: exit 0 02:19:39 INFO - TEST-OK | dromaeo_css | took 1118883ms 02:19:39 INFO - TEST-START | kraken 02:19:39 INFO - operating with platform_type : mac_ 02:19:39 INFO - Initialising browser for kraken test... 02:19:39 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpOlD2IV/profile http://localhost:56518/getInfo.html) 02:19:41 INFO - PROCESS | 1928 | __metrics Screen width/height:1600/1200 02:19:41 INFO - PROCESS | 1928 | colorDepth:24 02:19:41 INFO - PROCESS | 1928 | Browser inner width/height: 1024/689 02:19:41 INFO - PROCESS | 1928 | __metrics 02:19:41 INFO - PROCESS | 1928 | 1486376381847 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 02:19:41 INFO - PROCESS | 1928 | 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" 02:19:41 INFO - PROCESS | 1928 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:19:42 INFO - TEST-INFO | 1928: exit 0 02:19:42 INFO - Browser initialized. 02:19:42 INFO - Running cycle 1/1 for kraken test... 02:19:42 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpOlD2IV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:19:54 INFO - PROCESS | 1930 | RSS: Main: 213225472 02:19:54 INFO - PROCESS | 1930 | 02:19:57 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56518/tests/kraken/driver.html?testName=audio-beat-detection) 02:19:58 INFO - PROCESS | 1930 | RSS: Main: 266866688 02:19:58 INFO - PROCESS | 1930 | 02:20:01 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56518/tests/kraken/driver.html?testName=audio-dft) 02:20:01 INFO - PROCESS | 1930 | RSS: Main: 276238336 02:20:01 INFO - PROCESS | 1930 | 02:20:05 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56518/tests/kraken/driver.html?testName=audio-fft) 02:20:05 INFO - PROCESS | 1930 | RSS: Main: 374145024 02:20:05 INFO - PROCESS | 1930 | 02:20:07 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56518/tests/kraken/driver.html?testName=audio-oscillator) 02:20:08 INFO - PROCESS | 1930 | RSS: Main: 276922368 02:20:08 INFO - PROCESS | 1930 | 02:20:10 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56518/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:20:10 INFO - PROCESS | 1930 | RSS: Main: 278192128 02:20:10 INFO - PROCESS | 1930 | 02:20:13 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56518/tests/kraken/driver.html?testName=imaging-darkroom) 02:20:14 INFO - PROCESS | 1930 | RSS: Main: 317796352 02:20:14 INFO - PROCESS | 1930 | 02:20:17 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56518/tests/kraken/driver.html?testName=imaging-desaturate) 02:20:17 INFO - PROCESS | 1930 | RSS: Main: 319991808 02:20:17 INFO - PROCESS | 1930 | 02:20:20 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56518/tests/kraken/driver.html?testName=json-parse-financial) 02:20:21 INFO - PROCESS | 1930 | RSS: Main: 317886464 02:20:21 INFO - PROCESS | 1930 | 02:20:22 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56518/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:20:23 INFO - PROCESS | 1930 | RSS: Main: 295297024 02:20:23 INFO - PROCESS | 1930 | 02:20:25 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:20:26 INFO - PROCESS | 1930 | RSS: Main: 298328064 02:20:26 INFO - PROCESS | 1930 | 02:20:28 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:20:28 INFO - PROCESS | 1930 | RSS: Main: 300875776 02:20:28 INFO - PROCESS | 1930 | 02:20:31 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:20:32 INFO - PROCESS | 1930 | RSS: Main: 315236352 02:20:32 INFO - PROCESS | 1930 | 02:20:35 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:20:36 INFO - PROCESS | 1930 | RSS: Main: 308973568 02:20:36 INFO - PROCESS | 1930 | 02:20:37 INFO - PROCESS | 1930 | Cycle 1(1): loaded http://localhost:56518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56518/tests/kraken/driver.html?testName=ai-astar) 02:20:38 INFO - PROCESS | 1930 | RSS: Main: 353910784 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | __start_tp_report 02:20:38 INFO - PROCESS | 1930 | _x_x_mozilla_page_load 02:20:38 INFO - PROCESS | 1930 | _x_x_mozilla_page_load_details 02:20:38 INFO - PROCESS | 1930 | |i|pagename|runs| 02:20:38 INFO - PROCESS | 1930 | |0;ai-astar;116;114;113;114;114;115;113;113;113;120 02:20:38 INFO - PROCESS | 1930 | |1;audio-beat-detection;139;112;105;161;133;141;112;110;128;110 02:20:38 INFO - PROCESS | 1930 | |2;audio-dft;185;184;185;184;183;184;185;187;183;185 02:20:38 INFO - PROCESS | 1930 | |3;audio-fft;81;57;58;91;62;59;58;59;74;61 02:20:38 INFO - PROCESS | 1930 | |4;audio-oscillator;88;70;87;69;68;69;81;68;70;104 02:20:38 INFO - PROCESS | 1930 | |5;imaging-gaussian-blur;91;87;89;88;91;86;90;88;90;87 02:20:38 INFO - PROCESS | 1930 | |6;imaging-darkroom;96;98;96;97;95;96;96;96;95;96 02:20:38 INFO - PROCESS | 1930 | |7;imaging-desaturate;88;85;85;87;86;86;87;86;89;85 02:20:38 INFO - PROCESS | 1930 | |8;json-parse-financial;55;54;54;54;55;56;57;57;56;58 02:20:38 INFO - PROCESS | 1930 | |9;json-stringify-tinderbox;65;75;69;81;78;71;71;71;71;72 02:20:38 INFO - PROCESS | 1930 | |10;stanford-crypto-aes;83;84;87;87;84;83;83;84;84;88 02:20:38 INFO - PROCESS | 1930 | |11;stanford-crypto-ccm;140;138;141;137;138;140;142;143;197;162 02:20:38 INFO - PROCESS | 1930 | |12;stanford-crypto-pbkdf2;170;169;171;170;171;168;170;168;167;171 02:20:38 INFO - PROCESS | 1930 | |13;stanford-crypto-sha256-iterative;67;67;67;67;65;66;65;66;65;66 02:20:38 INFO - PROCESS | 1930 | __end_tp_report 02:20:38 INFO - PROCESS | 1930 | __start_cc_report 02:20:38 INFO - PROCESS | 1930 | _x_x_mozilla_cycle_collect,2112 02:20:38 INFO - PROCESS | 1930 | __end_cc_report 02:20:38 INFO - PROCESS | 1930 | __startTimestamp1486376438095__endTimestamp 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | ------- Summary: start ------- 02:20:38 INFO - PROCESS | 1930 | Number of tests: 14 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#0] ai-astar Cycles:10 Average:114.50 Median:114.00 stddev:2.17 (1.9%) stddev-sans-first:2.24 02:20:38 INFO - PROCESS | 1930 | Values: 116.0 114.0 113.0 114.0 114.0 115.0 113.0 113.0 113.0 120.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:130.50 stddev:18.28 (14.0%) stddev-sans-first:18.69 02:20:38 INFO - PROCESS | 1930 | Values: 139.0 112.0 105.0 161.0 133.0 141.0 112.0 110.0 128.0 110.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.18 (0.6%) stddev-sans-first:1.24 02:20:38 INFO - PROCESS | 1930 | Values: 185.0 184.0 185.0 184.0 183.0 184.0 185.0 187.0 183.0 185.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#3] audio-fft Cycles:10 Average:66.00 Median:61.50 stddev:11.84 (19.3%) stddev-sans-first:11.25 02:20:38 INFO - PROCESS | 1930 | Values: 81.0 57.0 58.0 91.0 62.0 59.0 58.0 59.0 74.0 61.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:70.00 stddev:12.26 (17.5%) stddev-sans-first:12.39 02:20:38 INFO - PROCESS | 1930 | Values: 88.0 70.0 87.0 69.0 68.0 69.0 81.0 68.0 70.0 104.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:89.50 stddev:1.77 (2.0%) stddev-sans-first:1.67 02:20:38 INFO - PROCESS | 1930 | Values: 91.0 87.0 89.0 88.0 91.0 86.0 90.0 88.0 90.0 87.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 02:20:38 INFO - PROCESS | 1930 | Values: 96.0 98.0 96.0 97.0 95.0 96.0 96.0 96.0 95.0 96.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:86.50 stddev:1.35 (1.6%) stddev-sans-first:1.30 02:20:38 INFO - PROCESS | 1930 | Values: 88.0 85.0 85.0 87.0 86.0 86.0 87.0 86.0 89.0 85.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.43 (2.6%) stddev-sans-first:1.50 02:20:38 INFO - PROCESS | 1930 | Values: 55.0 54.0 54.0 54.0 55.0 56.0 57.0 57.0 56.0 58.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:71.50 stddev:4.55 (6.4%) stddev-sans-first:3.96 02:20:38 INFO - PROCESS | 1930 | Values: 65.0 75.0 69.0 81.0 78.0 71.0 71.0 71.0 71.0 72.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:1.89 (2.2%) stddev-sans-first:1.90 02:20:38 INFO - PROCESS | 1930 | Values: 83.0 84.0 87.0 87.0 84.0 83.0 83.0 84.0 84.0 88.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:141.50 stddev:18.72 (13.2%) stddev-sans-first:19.65 02:20:38 INFO - PROCESS | 1930 | Values: 140.0 138.0 141.0 137.0 138.0 140.0 142.0 143.0 197.0 162.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.50 Median:170.00 stddev:1.43 (0.8%) stddev-sans-first:1.51 02:20:38 INFO - PROCESS | 1930 | Values: 170.0 169.0 171.0 170.0 171.0 168.0 170.0 168.0 167.0 171.0 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - PROCESS | 1930 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.50 stddev:0.88 (1.3%) stddev-sans-first:0.87 02:20:38 INFO - PROCESS | 1930 | Values: 67.0 67.0 67.0 67.0 65.0 66.0 65.0 66.0 65.0 66.0 02:20:38 INFO - PROCESS | 1930 | -------- Summary: end -------- 02:20:38 INFO - PROCESS | 1930 | 02:20:38 INFO - TEST-INFO | 1930: exit 0 02:20:38 INFO - TEST-OK | kraken | took 59838ms 02:20:38 INFO - SUITE-END | took 1178s 02:20:39 INFO - Completed test suite (00:19:38) 02:20:39 INFO - javascript benchmark 02:20:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2814.9296267593313, 3301.0921996450975, 3323.9169020774484, 3353.7820041697305, 3386.8645254189832, 5669.433056694334, 5678.43215678432, 5704.77180912763, 5707.6290041147295, 5714.114312281593, 5605.635633683808, 5606.187102870082, 5615.382307946123, 5631.521320687736, 5721.828345149642, 5195.168772996321, 5202.115640341132, 5203.557697595699, 5211.973940130313, 5273.288106105665, 5181.637285390024, 5189.091908915924, 5293.285406470116, 5302.708351040691, 5334.0, 5176.275321454999, 5201.453847346031, 5208.8437346879655, 5218.66078704883, 5285.709285989288, 5218.373795144588, 5262.578993680497, 5265.341832270966, 5276.12943864262, 5276.920846187291, 5150.61370397218, 5205.583553315763, 5222.477752224785, 5246.994736137114, 5269.552088072518, 5539.944600553983, 5587.469190426903, 5624.156376543551, 5628.887422251573, 5681.119426488901, 6133.763142530635, 6149.41580549847, 6183.505319574423, 6184.752609895599, 6193.411625895533, 6155.784547540859, 6228.096925945758, 6236.6258024518675, 6241.407066328691, 6244.500439964792, 5629.887402251932, 5741.799037033684, 5748.8850222995725, 5757.366689664133, 5788.218590490272, 6180.351063138396, 6226.470749986302, 6238.875222495525, 6239.469645080219, 6247.500199983991, 6071.574989750675, 6071.696415179224, 6085.513158947274, 6086.908696369558, 6099.634021958608, 6167.753289868355, 6196.1635179251125, 6213.254409470893, 6239.939210334254, 6250.718717657716, 6089.269287685506, 6106.043442834774, 6121.971262793606, 6128.014278093809, 6186.350433204449, 6247.875042499216, 6260.874782504325, 6261.467775239064, 6264.404881536338, 6265.8120256392385, 4955.554000140005, 4996.125678006334, 5007.599392048627, 5015.623828212899, 5028.622853286018, 4350.673699472489, 4395.164918665443, 4401.6698747594055, 4429.977850110793, 4466.508684044752, 5754.597178197486, 5766.942330576724, 5771.509421699203, 5777.162311464898, 5779.595428319977, 5726.685032323262, 6075.088736689444, 6250.3749625037135, 6266.404691554295, 6296.937030629727, 5765.221695071195, 5821.708914554255, 5830.5044071254415, 5843.152742852262, 5858.326292476335, 5285.577153827761, 5451.509364157166, 5457.890842183134, 5458.590605704509, 5460.590455715837, 6297.149884765498, 6304.18045654062, 6307.400796924285, 6316.05259211122, 6352.237731472161, 6303.558750887394, 6324.747010119728, 6334.746610135543, 6335.619862808247, 6352.809415717536, 6274.874502510015, 6323.7786677466065, 6334.714937827901, 6347.90478142819, 6350.491960643045, 6070.817875463743, 6131.294901086432, 6138.109974053827, 6147.784827531015, 6181.629102253879, 4777.665563410667, 6187.257529096357, 6275.811725648238, 6308.810735677937, 6312.5265605078885, 6055.061465472859, 6115.785947491817, 6118.969405152946, 6124.14262003311, 6125.908111378244, 5759.164921086335, 5864.736086876015, 5880.705964701833, 5914.58597898143, 5980.102984552352, 5866.6480011199465, 5919.437653422918, 5938.881222375442, 5939.406059394199, 5968.8806223876145, 6047.455728984412, 6057.273127224585, 6076.1493390924425, 6076.513878889767, 6099.2985806632805, 5754.223179870832, 5801.100829371454, 5816.47651711348, 5848.444397782204, 5904.084866845646], "value": 5679.6440000403245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.53255945215614, 475.44135640622267, 480.73319307784163, 508.6769901112792, 516.9844904652861, 30509.542356864637, 32461.350772984526, 41873.371899421494, 42553.57446425535, 42736.0, 42932.35601465973, 43041.56958430415, 43152.784236078856, 43156.568434315726, 43245.702628921106, 9233.67682131124, 9459.905400946005, 9463.337566370323, 9476.289278304155, 9481.62073517062, 8471.957640211791, 8678.262347700453, 9571.856422153673, 11253.381064041474, 11469.655910322703, 11393.658190254264, 11457.828132578017, 11459.59891403801, 11483.368414737186, 11499.3100413975, 11345.205835591512, 11406.08751299894, 11422.14333924959, 11438.0, 11447.59933402331, 8412.0, 8621.396502244845, 10979.1765617579, 11264.0, 11301.03941165002, 40732.38901416481, 40737.38893916564, 41224.0, 41305.0, 41375.0, 3458.913527161816, 3986.1828325193264, 4049.7165198436123, 4080.8571699990357, 4087.734297270668, 22958.96684649195, 28753.281167970967, 28935.71064289351, 29199.124026279245, 29220.685069171937, 3236.579244698174, 3269.591301087364, 3347.0126312737802, 3349.1794510344876, 3359.1937934895695, 468904.0, 469187.6540617309, 470911.0, 471834.0, 472956.6352168252, 51552.22671659928, 62321.0, 62462.68768656128, 62739.0, 62839.6858015707, 64308.0, 64364.35635643677, 64481.67759161174, 64493.0, 64515.0, 13636.795448068287, 16758.1620918956, 16774.245158967875, 16780.909240899302, 16791.91604041972, 11817.704557386134, 12454.750904981849, 12498.875011249953, 12565.434555444845, 12571.748565028649, 14626.268686565629, 14628.926855365655, 16093.907791986967, 16102.03387796736, 16337.918310408371, 6856.828579285458, 7007.8598428031155, 7055.541389809646, 7060.046893669289, 7071.504994650428, 749.9512531685532, 867.5835598912431, 894.6779159502574, 916.7341470973477, 927.9953600232091, 28833.423331533253, 29862.398848408935, 29977.0, 30105.548416773767, 30106.247343816143, 1043.0925095167254, 1076.7308172956762, 1077.256692881909, 1089.929154604948, 1108.1854836695022, 15195.848041519665, 15624.453144139901, 15684.529464116094, 15731.685366292839, 16003.359865605245, 611515.0, 615907.0, 616351.0, 618704.0, 619496.0, 61364.38635613676, 61606.69196653988, 61678.691606542576, 61834.38165618376, 61900.76198476005, 1438.0699742604077, 1454.3600815641084, 1456.7450696128135, 1480.970380592382, 1486.4500134949922, 24399.75600244046, 28021.299467513476, 28941.85529072341, 29562.852185738935, 29628.0, 50366.0, 50668.0, 50782.0, 50848.74575627098, 50931.0, 523662.0, 527870.0, 528457.0, 528467.0, 529157.0, 25972.220833375028, 26291.07981220648, 26457.867710661325, 26537.07120250782, 26542.734572654797, 34395.45220465035, 39627.0, 44602.77698611616, 47714.04571908612, 49931.75170620764, 38622.6137738619, 38869.6113038866, 39720.20559588734, 40716.0, 40957.38563921603, 38873.80563097166, 39221.803890980365, 39260.214795704494, 40354.39468408035, 40644.39033414442, 35363.823180883934, 36925.446118308784, 36948.630513694516, 37679.81160094182, 38307.425388618634, 26012.739872601032, 30996.380072397973, 32336.514952276204, 32384.676153238164, 32560.0, 24321.0, 24443.51112977695, 24617.876910615334, 24912.26316052534, 25078.0], "value": 19875.06500765414, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.2351391273106, 394.4950463406839, 397.5527531527032, 401.99799001005, 421.00642483738375, 19206.80793192071, 20609.86645734484, 21053.894730526325, 21905.123795048217, 21905.67141492877, 21343.893280533615, 21836.781632183673, 22269.554608907813, 22508.662370064463, 22779.77220227797, 5814.912776308359, 5993.8801223975715, 6156.1996940397685, 6163.568550201488, 6169.352218017111, 5699.857503562403, 5936.703164841762, 5982.820515384546, 6001.06983417571, 6115.113308570277, 4975.4527002029745, 5955.076963070731, 5957.910631340534, 5976.820695379146, 5982.282126144869, 5706.971465142668, 5889.764409423639, 5944.405559444042, 5954.612950158225, 5972.044472884318, 5274.868128296785, 5277.6305658603915, 5278.313819203479, 5294.364676238837, 5304.496072873071, 26083.347916302246, 26839.46321073587, 26892.327691807863, 26954.730452695418, 27206.31984200391, 2790.079273839628, 2795.622590950216, 2816.7887408444244, 2820.9294767630972, 2835.1636267301196, 13505.514393416715, 15353.232338383035, 15807.288672009787, 16251.349946002145, 16688.58278543034, 1849.2695385322857, 1873.6721073812164, 1996.2713609532416, 1997.0, 2010.8793472391558, 98092.0, 104862.47568762107, 107777.46111269471, 107847.0, 147547.0, 11514.251573647685, 11862.584809531625, 12121.39393030345, 12144.635660930186, 12239.449224784907, 16062.518124456285, 16100.919495402448, 16328.346866125223, 16346.34614615426, 16380.26288817006, 7900.723474678473, 8291.419600628018, 8315.501069935823, 8558.786030349167, 8714.607842646968, 6509.609423434515, 8629.43908645936, 8747.825043499095, 8750.737477875675, 8896.199342059117, 4885.291632713308, 4901.583365413909, 4905.362302900671, 4916.631252656065, 4978.4025916890205, 1969.5371587676884, 2014.8589598728242, 2022.5853699991467, 2024.5444774925818, 2027.9188832446832, 849.8555245608261, 901.8241442918568, 901.9458832470073, 920.995395023034, 933.1321870660352, 21844.78155218459, 22840.771592284196, 22876.542469150525, 22881.427964301027, 22883.542329153322, 913.9131782480787, 915.272358475014, 917.6788124156625, 932.1517419148543, 934.2525979216599, 8950.910490895008, 10141.0, 11647.41762911851, 11657.358845263423, 11783.234089784137, 650099.7495012495, 727028.3648581724, 740775.0, 751780.0, 764985.0, 115553.4222328883, 135060.9740853888, 148859.76710349353, 150145.0, 160477.0, 856.7087190355309, 1068.3643232276784, 1074.8226542620537, 1079.9298045627008, 1084.7017070305571, 19856.801431985496, 20963.58072838504, 21348.573028539653, 21443.142274309477, 21461.0, 53340.0, 53990.920181596935, 58150.12774808297, 62332.376676232656, 62642.686786567596, 93422.0, 93509.0, 95092.0, 97474.12629368361, 97565.0, 7352.926470735224, 7975.521468711896, 8585.82828343442, 8653.004904435818, 8844.513551754715, 10996.615118470816, 12780.0, 13740.725185496434, 13839.930800345934, 13965.790513142514, 11362.886371136183, 11646.88353116458, 11785.116116291483, 11982.281063136239, 11983.400829958293, 10632.468376581295, 11514.136439766884, 11537.307761534335, 11816.822747658964, 11869.347185904859, 9944.502774861083, 10688.679339619825, 11679.124065694938, 11843.526258949545, 11872.76254474923, 8546.871796922927, 8572.78568035779, 8588.398812083095, 8743.562821858754, 8877.201051905102, 9068.909310906807, 11504.482298296763, 11646.417679116179, 11763.0, 11970.0], "value": 10144.771346143805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.3618135657598, 1431.870213941739, 1450.7098580283946, 1475.0412232049173, 1476.1217075839875, 8103.635336409861, 8529.445586036903, 8530.061693213742, 8554.315654747614, 8564.443311184768, 7407.444441666883, 7712.4215683823795, 7718.768436946886, 7983.241592048761, 8208.425410221287, 2300.3329034579992, 2339.7426283108844, 2341.4263505441104, 2344.0623750499767, 2348.5068135691527, 2320.14154762738, 2342.390978345625, 2355.893984770681, 2364.373441038126, 2369.786719195272, 2309.364924645719, 2317.7450480447137, 2342.6251799711963, 2347.366211122996, 2349.259983105325, 2251.4146321956327, 2271.670607761882, 2282.44080200352, 2320.6983092198075, 2323.1056043423237, 2288.187693368863, 2352.270796053229, 2353.0234952494693, 2359.596040355985, 2361.9173328933407, 8391.0, 8446.155384461566, 8462.15378462155, 8470.110638383005, 8484.0, 5059.3169922060415, 5235.0576896159055, 5243.659162154448, 5244.60665450089, 5249.42256351801, 3021.6676165621766, 3242.7243684286864, 3254.2840575073446, 3474.2356681530277, 3498.422760244582, 1042.5412818359898, 1211.497228650109, 1218.8293638890473, 1242.1180961517334, 1264.9494020239179, 10659.840102398548, 10759.946200269027, 10845.295055821347, 10870.673879783619, 10882.673519794418, 4472.038511719931, 4684.320773487825, 4750.667453278237, 4764.595009424238, 4769.6661233714, 9297.442153470813, 9493.05069493045, 9546.713598592054, 9558.0, 9568.282378821616, 5282.867928301746, 5284.392294886137, 5297.152455607162, 5300.549453296436, 5301.761420736076, 4243.875373026151, 4299.871003869889, 4354.586314300136, 4814.277858321279, 4863.392075990501, 3894.5521265054313, 3926.371780515104, 4008.9198216035516, 4020.3567429211184, 4036.5156181258435, 1107.565702415358, 1280.7950727883494, 1282.3139620303093, 1288.916220445668, 1296.3129541343103, 800.1798156889118, 807.4953154278576, 808.401782680812, 818.0061225610912, 828.3598475019495, 4587.655925805578, 4893.584045356113, 5346.465353464622, 5628.915566266509, 5673.659580425187, 556.5297323761415, 575.4964406144624, 578.17032558279, 596.785157343356, 598.6709504899117, 3098.806959320633, 3127.4214270360058, 3777.1690228149764, 3815.1797363566966, 3894.162755007687, 9705.854412183822, 9886.80226395468, 10047.09576138151, 10222.437765922945, 10223.335483193569, 9799.216062715108, 9896.307258491837, 9932.602695892083, 9973.0, 10029.64896228643, 7818.921810781933, 7822.647980840876, 7865.646045927947, 7877.212278772192, 7999.200079991954, 5458.0, 5481.917771233355, 5585.581081418991, 5665.206871037975, 5671.461211184931, 9245.445273283625, 9336.439813611205, 9423.0, 9436.292278079034, 9472.242220622471, 9066.818663626822, 9386.108319709616, 9422.811543769223, 9451.669191578261, 9541.998090200428, 6611.702473388806, 6815.318468153145, 6815.454763618998, 7121.252268511732, 7252.5648461092505, 7634.809129771578, 7793.9220607793195, 7910.920890791019, 8004.719834805754, 8019.6391162398995, 7034.5427547209265, 7115.359617634438, 7122.679479423542, 7161.606111663908, 7221.205667376479, 6825.0, 6965.357324124366, 7016.298370162943, 7028.226895041644, 7142.678579464041, 6397.584157029778, 6402.935970640234, 6505.2518960318275, 6514.0, 6516.152900122954, 5000.049990501793, 5022.397312322399, 5053.115704751727, 5065.417476990193, 5068.771905264346, 4916.434610019894, 4950.673183603701, 5049.899002020013, 5076.746162691777, 5096.847094587169], "value": 4350.783458581098, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.833519910443, 349.09584176981616, 358.43546414397315, 370.28165359203126, 370.78679759138504, 4564.908701825966, 5082.03441346144, 5133.204353325236, 5153.7680804363845, 5232.529072383494, 4587.908241835161, 4769.713817170972, 5067.037262920043, 5102.719350435725, 5147.536721695056, 3787.924241515168, 4061.2689715851134, 4064.7764372959477, 4073.796310184494, 4077.8980525486804, 3484.4773284007474, 3959.762414255154, 3963.821628026731, 4041.2119636670714, 4062.4109504121884, 3012.7891047626676, 3338.532605435241, 3350.698437140657, 4023.0, 4051.169510250392, 3500.03748969033, 3766.5856755756845, 3884.961150388488, 4023.772749311459, 4085.916182746791, 2692.784577233836, 3496.5629296337956, 3506.1935754776546, 3539.1682954505673, 3580.695640870529, 5066.290719299301, 5112.769925353368, 5125.103711626722, 5153.613478989091, 5209.947900520984, 4622.329762184464, 4624.28323609841, 4634.768261586941, 4682.08699303636, 4747.952520474785, 4958.975205123951, 5120.745417372767, 5217.921731174036, 5462.781488740446, 5478.287822583039, 3963.5243770747693, 3970.9007274818073, 3992.6606238469767, 4009.599040095967, 4009.959900401017, 4824.445188803263, 5421.810236641738, 5608.887822243532, 5652.63257888236, 5729.312482502085, 3333.8999830005137, 3416.692497675221, 3888.8444462221196, 4052.615001574846, 4056.3307054335705, 3219.661935496786, 3541.7166626669805, 3966.5835087315995, 4085.97957010219, 4166.854115118306, 4066.9796651017145, 4393.253146965024, 4429.2027435062, 4498.820047198075, 4663.323818046364, 5063.8987220255385, 5447.291852059207, 5463.480969307989, 5470.64440811346, 5492.423295553989, 1230.9384530773425, 1240.6154092231257, 1317.6494093553993, 1334.0994828490923, 1335.4124185358412, 755.029786724057, 772.6407220642428, 797.0236460336042, 828.2918105020121, 936.9786932243887, 4133.958660413357, 4633.467151277646, 4717.669763116549, 4777.407178094208, 4859.562639362474, 5232.764525596357, 5323.600729945269, 5324.361076670824, 5354.152817278033, 5372.8656783579945, 4014.7189696721534, 4453.55464453552, 4717.8348757794, 4843.515648435128, 4854.490278520775, 2535.3773584905307, 2775.791815613837, 2787.2161816437497, 2792.1571762636563, 2794.4690508803264, 5174.730847051198, 5183.0, 5451.127819548853, 5523.364813046551, 5545.528630066489, 4694.6009589185305, 5200.0, 5386.542143917733, 5595.916061259084, 5733.054046082433, 4918.827841025547, 5491.65454463651, 5744.971275143681, 5818.2436283282905, 5884.411558844081, 5301.469853014668, 5374.570034397317, 5661.320641523043, 5679.1258884568815, 5693.4875861172695, 4806.663533552758, 5186.870328241824, 5586.776528938797, 5601.411851755507, 5612.354579223278, 4807.807687692453, 5507.889842203213, 5535.280413546374, 5641.689707066151, 5687.829365119053, 3357.7145942595153, 3793.2982398256413, 3802.4106263529197, 3804.0599294360627, 3848.8075596220638, 5223.753785872112, 5665.3201615806365, 5688.8382414682155, 5694.690221249053, 5707.800226992035, 4772.403449568804, 5317.335333083364, 5463.159157238293, 5491.258680078298, 5514.862128446661, 4801.375821143229, 5332.280536926273, 5359.249705041375, 5378.435264297349, 5389.487998640123, 4169.520505141826, 5300.575953923754, 5327.600429967845, 5360.597955153301, 5400.891982160413, 5388.919166212431, 5437.972810135924, 5455.0815313806925, 5481.287432633732, 5493.478119578634, 4233.322668373045, 5130.51260130287, 5247.737613119405, 5367.584586935816, 5367.704776237344], "value": 3984.276608914564, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.8930660400939, 725.0574253470487, 775.7323723315458, 805.7743831727119, 810.3638643664724, 15435.536933891986, 15828.76256856148, 15978.201089945514, 16030.278637461313, 16031.358745650175, 14431.422743090263, 14558.927205363985, 14614.561563153096, 14617.0, 14667.193304368255, 674.2751542092246, 694.2987582541645, 697.6476879176034, 842.3261390887296, 854.2013217641525, 844.3456321350941, 867.4578388507183, 870.4820631724145, 871.5859966515918, 873.0919843362894, 928.2805825485235, 929.3866048408051, 929.3866048408051, 929.8837645294338, 930.3812964378681, 717.1716667249283, 921.3964853021282, 922.3082687984012, 923.0630909626667, 923.2752289452748, 544.0289083985068, 657.7927952694886, 673.2526895146433, 673.9056532085511, 684.1379861374704, 14930.552083437515, 14938.32777525014, 14970.17664028478, 15209.695806083817, 15437.614059648598, 13931.721365572734, 13947.232902190375, 13965.650858728613, 14002.019858609901, 14034.157950523002, 12434.81347779784, 12523.43644535989, 12655.493780248778, 12828.230306181658, 12984.740305193938, 3549.6095429502734, 3678.0988657779017, 3712.981435092834, 3842.1931394407206, 3844.519435070616, 109230.0, 109852.0, 110042.44978775135, 110466.44766776195, 111621.44189279001, 8980.595873185723, 10631.893681063246, 10636.989485998818, 10828.78342433147, 11090.889091109148, 21344.893275533523, 22086.0, 22098.558028839332, 22637.660435093487, 22709.318720438412, 7827.178146294671, 7947.721829736047, 7951.761447156595, 7961.681532738626, 8149.388795840336, 4271.273883439822, 4316.69783115179, 4326.0, 4390.341448782644, 4513.187626227311, 3565.180008598037, 3607.6933460656137, 3620.094976255936, 3626.9300556335943, 3657.6708096271464, 564.6950646650845, 654.751194546079, 658.1509852290587, 662.2582707367683, 672.9730810767612, 684.4968947823346, 684.6456536428312, 700.7547358424613, 717.1072015340934, 724.8948902409121, 10316.948415257875, 10415.59697512002, 10431.16550675944, 10507.10689591378, 10526.52630631608, 692.200508412774, 716.4304378019488, 721.7257442171941, 723.4682508356353, 726.9018682477798, 4778.37881075458, 5071.137906555894, 5076.162433198555, 5111.616628752859, 5120.18077107661, 97520.09919603278, 101925.0, 102736.97263027274, 103103.0, 105973.94026059796, 91777.54111229401, 92254.0, 92826.0, 93824.0, 94986.0, 55078.72460637671, 64238.0, 69895.55365562181, 79109.20890791134, 80886.0, 9704.70885873425, 9888.555015024485, 9911.355761875455, 9919.900800991898, 9935.0, 48529.514704852496, 52869.47130528645, 53441.198382025075, 54486.18270725863, 54508.72745636247, 57646.0, 61358.69320653368, 64726.352736472036, 69436.65281673559, 70537.29462705512, 12834.486620535074, 13432.059755817, 13437.126586771828, 13625.727485450434, 13866.0, 14002.859971400156, 14114.16203708341, 14371.922105842314, 15058.774118388452, 15675.137867417401, 13369.197848129143, 13555.525556605471, 13860.92551348676, 14022.929885350508, 14489.565313040626, 13436.731265374443, 13706.65733356674, 14089.223418398407, 14233.857661423668, 14538.273086345853, 13484.0, 13671.453141874214, 13803.2408217549, 13804.03371763967, 13805.654858628615, 10570.365778053034, 10613.357114546052, 11653.0, 11681.874844178508, 12051.75896482083, 10133.661206520259, 10667.679969600924, 11453.770924581628, 11683.707907302385, 11727.941360293486], "value": 7593.07061572144, "unit": "score"}], "name": "dromaeo_css", "value": 7295.292540758214, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 113.0, 114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 120.0], "value": 114.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 105.0, 161.0, 133.0, 141.0, 112.0, 110.0, 128.0, 110.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 185.0, 184.0, 183.0, 184.0, 185.0, 187.0, 183.0, 185.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 58.0, 91.0, 62.0, 59.0, 58.0, 59.0, 74.0, 61.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 87.0, 69.0, 68.0, 69.0, 81.0, 68.0, 70.0, 104.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 89.0, 88.0, 91.0, 86.0, 90.0, 88.0, 90.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 96.0, 97.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 87.0, 86.0, 86.0, 87.0, 86.0, 89.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 55.0, 56.0, 57.0, 57.0, 56.0, 58.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 69.0, 81.0, 78.0, 71.0, 71.0, 71.0, 71.0, 72.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 87.0, 87.0, 84.0, 83.0, 83.0, 84.0, 84.0, 88.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 141.0, 137.0, 138.0, 140.0, 142.0, 143.0, 197.0, 162.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 171.0, 170.0, 171.0, 168.0, 170.0, 168.0, 167.0, 171.0], "value": 169.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 65.0, 66.0, 65.0, 66.0, 65.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1434.8, "alertThreshold": 2.0}]} 02:20:39 INFO - Return code: 0 02:20:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:20:39 ERROR - # TBPL SUCCESS # 02:20:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:20:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:20:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:20:39 INFO - 2017-02-06 02:20:39.436 screenresolution[1935:23517] starting screenresolution argv=screenresolution get 02:20:39 INFO - 2017-02-06 02:20:39.438 screenresolution[1935:23517] Display 0: 1600x1200x32@60 02:20:39 INFO - 2017-02-06 02:20:39.444 screenresolution[1936:23519] starting screenresolution argv=screenresolution list 02:20:39 INFO - Available Modes on Display 0 02:20:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:20:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:20:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:20:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:20:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:20:39 INFO - Intel Iris: 02:20:39 INFO - Chipset Model: Intel Iris 02:20:39 INFO - Type: GPU 02:20:39 INFO - Bus: Built-In 02:20:39 INFO - VRAM (Dynamic, Max): 1536 MB 02:20:39 INFO - Vendor: Intel (0x8086) 02:20:39 INFO - Device ID: 0x0a2e 02:20:39 INFO - Revision ID: 0x0009 02:20:39 INFO - Displays: 02:20:39 INFO - Display: 02:20:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:20:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:20:39 INFO - Display Serial Number: 1600x1200 60 02:20:39 INFO - Main Display: Yes 02:20:39 INFO - Mirror: Off 02:20:39 INFO - Online: Yes 02:20:39 INFO - Rotation: Supported 02:20:39 INFO - Return code: 0 02:20:39 INFO - Running post-action listener: _resource_record_post_action 02:20:39 INFO - [mozharness: 2017-02-06 10:20:39.677627Z] Finished run-tests step (success) 02:20:39 INFO - Running post-run listener: _resource_record_post_run 02:20:39 INFO - Total resource usage - Wall time: 1200s; CPU: 53.0%; Read bytes: 815104; Write bytes: 508752384; Read time: 14; Write time: 2066 02:20:39 INFO - TinderboxPrint: CPU usage
53.4% 02:20:39 INFO - TinderboxPrint: I/O read bytes / time
815,104 / 14 02:20:39 INFO - TinderboxPrint: I/O write bytes / time
508,752,384 / 2,066 02:20:39 INFO - TinderboxPrint: CPU idle
2,238.8 (46.6%) 02:20:39 INFO - TinderboxPrint: CPU system
960.4 (20.0%) 02:20:39 INFO - TinderboxPrint: CPU user
1,602.5 (33.4%) 02:20:39 INFO - TinderboxPrint: Swap in / out
208,748,544 / 0 02:20:39 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200905216; Write bytes: 201238528; Read time: 15845; Write time: 489 02:20:39 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 815104; Write bytes: 296835584; Read time: 14; Write time: 1541 02:20:40 INFO - Running post-run listener: _upload_blobber_files 02:20:40 INFO - Blob upload gear active. 02:20:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:20:40 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 02:20:40 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'] 02:20:40 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 02:20:40 INFO - (blobuploader) - INFO - Open directory for files ... 02:20:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:20:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:20:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:20:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:20:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:20:41 INFO - (blobuploader) - INFO - Done attempting. 02:20:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:20:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:20:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:20:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:20:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:20:42 INFO - (blobuploader) - INFO - Done attempting. 02:20:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:20:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:20:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:20:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:20:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:20:43 INFO - (blobuploader) - INFO - Done attempting. 02:20:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:20:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:20:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:20:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:20:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:20:45 INFO - (blobuploader) - INFO - Done attempting. 02:20:45 INFO - (blobuploader) - INFO - Iteration through files over. 02:20:45 INFO - Return code: 0 02:20:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:20:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:20:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a49301f4fa4e6a012b99885f7ea87411d907f3028b968a6fe6efe1dce020d3c026a3a75585d89c9b279e21fcb4dd49a3e6973cf5d55e2da90750e03cc7e3fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e2fa185f1cdeb61b8f81f6ee261b2efcf580dd2b448407a3da3258756e18640741293e11d6b5057970df4ef906254b83c0085808001642d3b2845f89d0e017", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40e28dbc27c1d904af44ef001a4626535829eecac13ab83ee0f45cd0d534463f0e61340c803e481dbf1a6e5d45544beebcd4d3ca85d5dad02138f93f7c4cdc3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f09889aa45692f19b589cb15e2dd26c289fc88495ade095541a72ae6314a2cac5d5dc7641af19783ad85ea2bdf51866917dcfb9ad90918cb6ce7378f57bab114"} 02:20:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:20:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:20:45 INFO - Contents: 02:20:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a49301f4fa4e6a012b99885f7ea87411d907f3028b968a6fe6efe1dce020d3c026a3a75585d89c9b279e21fcb4dd49a3e6973cf5d55e2da90750e03cc7e3fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e2fa185f1cdeb61b8f81f6ee261b2efcf580dd2b448407a3da3258756e18640741293e11d6b5057970df4ef906254b83c0085808001642d3b2845f89d0e017", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40e28dbc27c1d904af44ef001a4626535829eecac13ab83ee0f45cd0d534463f0e61340c803e481dbf1a6e5d45544beebcd4d3ca85d5dad02138f93f7c4cdc3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f09889aa45692f19b589cb15e2dd26c289fc88495ade095541a72ae6314a2cac5d5dc7641af19783ad85ea2bdf51866917dcfb9ad90918cb6ce7378f57bab114"} 02:20:45 INFO - Running post-run listener: copy_logs_to_upload_dir 02:20:45 INFO - Copying logs to upload dir... 02:20:45 INFO - mkdir: /builds/slave/test/build/upload/logs 02:20:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.791179 ========= master_lag: 2.43 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-06 02:20:47.942265) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 02:20:47.946417) ========= 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.2Zx5VVDhpx/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.OFVAHovodq/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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/d8a49301f4fa4e6a012b99885f7ea87411d907f3028b968a6fe6efe1dce020d3c026a3a75585d89c9b279e21fcb4dd49a3e6973cf5d55e2da90750e03cc7e3fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e2fa185f1cdeb61b8f81f6ee261b2efcf580dd2b448407a3da3258756e18640741293e11d6b5057970df4ef906254b83c0085808001642d3b2845f89d0e017", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40e28dbc27c1d904af44ef001a4626535829eecac13ab83ee0f45cd0d534463f0e61340c803e481dbf1a6e5d45544beebcd4d3ca85d5dad02138f93f7c4cdc3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f09889aa45692f19b589cb15e2dd26c289fc88495ade095541a72ae6314a2cac5d5dc7641af19783ad85ea2bdf51866917dcfb9ad90918cb6ce7378f57bab114"} build_url:https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010980 build_url: 'https://queue.taskcluster.net/v1/task/fjX77gP1TreQsEMRyxbSMQ/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8a49301f4fa4e6a012b99885f7ea87411d907f3028b968a6fe6efe1dce020d3c026a3a75585d89c9b279e21fcb4dd49a3e6973cf5d55e2da90750e03cc7e3fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e2fa185f1cdeb61b8f81f6ee261b2efcf580dd2b448407a3da3258756e18640741293e11d6b5057970df4ef906254b83c0085808001642d3b2845f89d0e017", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40e28dbc27c1d904af44ef001a4626535829eecac13ab83ee0f45cd0d534463f0e61340c803e481dbf1a6e5d45544beebcd4d3ca85d5dad02138f93f7c4cdc3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f09889aa45692f19b589cb15e2dd26c289fc88495ade095541a72ae6314a2cac5d5dc7641af19783ad85ea2bdf51866917dcfb9ad90918cb6ce7378f57bab114"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 02:20:47.982805) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 02:20:47.983331) ========= 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.2Zx5VVDhpx/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.OFVAHovodq/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.005145 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 02:20:48.032968) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 02:20:48.033286) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 02:20:48.033619) ========= ========= Total master_lag: 2.76 =========