builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0135 starttime: 1494318742.83 results: success (0) buildid: 20170509004016 builduid: 3774cf73f4e742c3bfe93a2b75221d27 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:22.830422) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:22.830823) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:22.831134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:22.857715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:22.858393) ========= 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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-05-09 01:32:22-- 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% 7.95M=0.001s 2017-05-09 01:32:22 (7.95 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110328 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:22.997520) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:22.998290) ========= 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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.020399 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:23.050693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-09 01:32:23.051896) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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-05-09 01:32:23,131 truncating revision to first 12 chars 2017-05-09 01:32:23,131 Setting DEBUG logging. 2017-05-09 01:32:23,131 attempt 1/10 2017-05-09 01:32:23,131 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-09 01:32:24,746 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.874174 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-09 01:32:24.951980) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:24.952363) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:24.952839) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:24.953207) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 01:32:24.974224) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-09 01:32:24.974629) ========= /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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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:32:25 INFO - MultiFileLogger online at 20170509 01:32:25 in /builds/slave/test 01:32:25 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:32:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:32:25 INFO - {'append_to_log': False, 01:32:25 INFO - 'base_work_dir': '/builds/slave/test', 01:32:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:32:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:32:25 INFO - 'branch': 'Mozilla-Aurora', 01:32:25 INFO - 'buildbot_json_path': 'buildprops.json', 01:32:25 INFO - 'config_files': ('talos/mac_config.py',), 01:32:25 INFO - 'default_actions': ('clobber', 01:32:25 INFO - 'read-buildbot-config', 01:32:25 INFO - 'download-and-extract', 01:32:25 INFO - 'populate-webroot', 01:32:25 INFO - 'create-virtualenv', 01:32:25 INFO - 'install', 01:32:25 INFO - 'run-tests'), 01:32:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:32:25 INFO - 'download_minidump_stackwalk': True, 01:32:25 INFO - 'download_symbols': 'ondemand', 01:32:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:32:25 INFO - 'tooltool.py': '/tools/tooltool.py', 01:32:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:32:25 INFO - '/tools/misc-python/virtualenv.py')}, 01:32:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:32:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:32:25 INFO - 'gecko_profile': False, 01:32:25 INFO - 'gecko_profile_interval': 0, 01:32:25 INFO - 'installer_path': 'installer.exe', 01:32:25 INFO - 'log_level': 'info', 01:32:25 INFO - 'log_name': 'talos', 01:32:25 INFO - 'log_to_console': True, 01:32:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:32:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:32:25 INFO - 'opt_config_files': (), 01:32:25 INFO - 'pip_index': False, 01:32:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:32:25 INFO - 'cmd': ('bash', 01:32:25 INFO - '-c', 01:32:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:32:25 INFO - 'enabled': True, 01:32:25 INFO - 'halt_on_failure': False, 01:32:25 INFO - 'name': 'check_screen_resolution'},), 01:32:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:32:25 INFO - 'cmd': ('bash', 01:32:25 INFO - '-c', 01:32:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:32:25 INFO - 'enabled': True, 01:32:25 INFO - 'halt_on_failure': False, 01:32:25 INFO - 'name': 'check_screen_resolution'},), 01:32:25 INFO - 'run_cmd_checks_enabled': True, 01:32:25 INFO - 'suite': 'dromaeojs', 01:32:25 INFO - 'system_bits': '32', 01:32:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:32:25 INFO - 'title': 't-yosemite-r7-0135', 01:32:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:32:25 INFO - 'use_talos_json': True, 01:32:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:32:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:32:25 INFO - 'work_dir': 'build'} 01:32:25 INFO - [mozharness: 2017-05-09 08:32:25.181917Z] Running clobber step. 01:32:25 INFO - Running pre-action listener: _resource_record_pre_action 01:32:25 INFO - Running main action method: clobber 01:32:25 INFO - rmtree: /builds/slave/test/build 01:32:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:32:27 INFO - Running post-action listener: _resource_record_post_action 01:32:27 INFO - [mozharness: 2017-05-09 08:32:27.052571Z] Finished clobber step (success) 01:32:27 INFO - [mozharness: 2017-05-09 08:32:27.052700Z] Running read-buildbot-config step. 01:32:27 INFO - Running pre-action listener: _resource_record_pre_action 01:32:27 INFO - Running main action method: read_buildbot_config 01:32:27 INFO - Using buildbot properties: 01:32:27 INFO - { 01:32:27 INFO - "project": "", 01:32:27 INFO - "product": "firefox", 01:32:27 INFO - "script_repo_revision": "production", 01:32:27 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:32:27 INFO - "repository": "", 01:32:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:32:27 INFO - "buildid": "20170509004016", 01:32:27 INFO - "stage_platform": "macosx64", 01:32:27 INFO - "basedir": "/builds/slave/test", 01:32:27 INFO - "nightly_build": "True", 01:32:27 INFO - "buildnumber": 2, 01:32:27 INFO - "slavename": "t-yosemite-r7-0135", 01:32:27 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 01:32:27 INFO - "platform": "yosemite_r7", 01:32:27 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:32:27 INFO - "branch": "mozilla-aurora", 01:32:27 INFO - "repo_path": "releases/mozilla-aurora", 01:32:27 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:32:27 INFO - "pgo_build": "False", 01:32:27 INFO - "builduid": "3774cf73f4e742c3bfe93a2b75221d27", 01:32:27 INFO - "slavebuilddir": "test" 01:32:27 INFO - } 01:32:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:32:27 INFO - Running post-action listener: _resource_record_post_action 01:32:27 INFO - [mozharness: 2017-05-09 08:32:27.056261Z] Finished read-buildbot-config step (success) 01:32:27 INFO - [mozharness: 2017-05-09 08:32:27.056380Z] Running download-and-extract step. 01:32:27 INFO - Running pre-action listener: _resource_record_pre_action 01:32:27 INFO - Running main action method: download_and_extract 01:32:27 INFO - mkdir: /builds/slave/test/build/tests 01:32:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:32:27 INFO - trying https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:32:27 INFO - Downloading https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:32:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:32:28 INFO - Downloaded 1199 bytes. 01:32:28 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:32:28 INFO - Using the following test package requirements: 01:32:28 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:32:28 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:32:28 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:32:28 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'jsshell-mac.zip'], 01:32:28 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:32:28 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:32:28 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:32:28 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:32:28 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:32:28 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:32:28 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:32:28 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:32:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:32:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:32:28 INFO - Fetch https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:32:29 INFO - Expected file size: 51791231 01:32:29 INFO - Obtained file size: 51791231 01:32:46 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:32:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:32:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:32:46 INFO - Fetch https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:32:48 INFO - Expected file size: 51791231 01:32:48 INFO - Obtained file size: 51791231 01:33:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:33:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:33:03 INFO - Fetch https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:33:05 INFO - Expected file size: 14404424 01:33:05 INFO - Obtained file size: 14404424 01:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:05 INFO - trying https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:33:05 INFO - Downloading https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:33:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:33:07 INFO - Downloaded 74779062 bytes. 01:33:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:33:07 INFO - mkdir: /builds/slave/test/properties 01:33:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:33:07 INFO - Writing to file /builds/slave/test/properties/build_url 01:33:07 INFO - Contents: 01:33:07 INFO - build_url:https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:33:08 INFO - Running post-action listener: _resource_record_post_action 01:33:08 INFO - Running post-action listener: set_extra_try_arguments 01:33:08 INFO - [mozharness: 2017-05-09 08:33:08.166447Z] Finished download-and-extract step (success) 01:33:08 INFO - [mozharness: 2017-05-09 08:33:08.166589Z] Running populate-webroot step. 01:33:08 INFO - Running pre-action listener: _resource_record_pre_action 01:33:08 INFO - Running main action method: populate_webroot 01:33:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:33:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:33:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:33:08 INFO - '--tppagecycles', 01:33:08 INFO - '7'], 01:33:08 INFO - 'tests': ['tsvgm']}}, 01:33:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:33:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:33:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:33:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:33:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:33:08 INFO - 'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:33:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:33:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:33:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:33:08 INFO - 'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['damp', 'tps']}, 01:33:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:33:08 INFO - 'tests': ['damp', 'tps']}, 01:33:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['dromaeo_dom']}, 01:33:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:33:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['basic_compositor_video']}, 01:33:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:33:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['a11yr', 01:33:08 INFO - 'ts_paint', 01:33:08 INFO - 'tpaint', 01:33:08 INFO - 'sessionrestore', 01:33:08 INFO - 'sessionrestore_no_auto_restore', 01:33:08 INFO - 'tabpaint']}, 01:33:08 INFO - 'other-e10s': {'tests': ['a11yr', 01:33:08 INFO - 'ts_paint', 01:33:08 INFO - 'tpaint', 01:33:08 INFO - 'sessionrestore', 01:33:08 INFO - 'sessionrestore_no_auto_restore', 01:33:08 INFO - 'tabpaint']}, 01:33:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:33:08 INFO - 'ts_paint', 01:33:08 INFO - 'tpaint', 01:33:08 INFO - 'sessionrestore', 01:33:08 INFO - 'sessionrestore_no_auto_restore', 01:33:08 INFO - 'tabpaint']}, 01:33:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:33:08 INFO - 'ts_paint', 01:33:08 INFO - 'tpaint', 01:33:08 INFO - 'sessionrestore', 01:33:08 INFO - 'sessionrestore_no_auto_restore', 01:33:08 INFO - 'tabpaint']}, 01:33:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['a11yr', 01:33:08 INFO - 'ts_paint', 01:33:08 INFO - 'tpaint', 01:33:08 INFO - 'sessionrestore', 01:33:08 INFO - 'sessionrestore_no_auto_restore', 01:33:08 INFO - 'tabpaint']}, 01:33:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['a11yr', 01:33:08 INFO - 'ts_paint', 01:33:08 INFO - 'tpaint', 01:33:08 INFO - 'sessionrestore', 01:33:08 INFO - 'sessionrestore_no_auto_restore', 01:33:08 INFO - 'tabpaint']}, 01:33:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['tsvgx', 01:33:08 INFO - 'tsvgr_opacity', 01:33:08 INFO - 'tart', 01:33:08 INFO - 'tscrollx', 01:33:08 INFO - 'cart', 01:33:08 INFO - 'tsvg_static']}, 01:33:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:33:08 INFO - 'tsvgr_opacity', 01:33:08 INFO - 'tart', 01:33:08 INFO - 'tscrollx', 01:33:08 INFO - 'cart', 01:33:08 INFO - 'tsvg_static']}, 01:33:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:33:08 INFO - 'talos_options': ['--disable-e10s'], 01:33:08 INFO - 'tests': ['tp5o']}, 01:33:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:33:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:33:08 INFO - 'talos_options': ['--disable-e10s', 01:33:08 INFO - '--xperf_path', 01:33:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:33:08 INFO - 'tests': ['tp5n']}, 01:33:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:33:08 INFO - 'talos_options': ['--xperf_path', 01:33:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:33:08 INFO - 'tests': ['tp5n']}}, 01:33:08 INFO - 'talos.zip': {'path': '', 01:33:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:33:08 INFO - Running post-action listener: _resource_record_post_action 01:33:08 INFO - [mozharness: 2017-05-09 08:33:08.185275Z] Finished populate-webroot step (success) 01:33:08 INFO - [mozharness: 2017-05-09 08:33:08.185417Z] Running create-virtualenv step. 01:33:08 INFO - Running pre-action listener: _resource_record_pre_action 01:33:08 INFO - Running main action method: create_virtualenv 01:33:08 INFO - Creating virtualenv /builds/slave/test/build/venv 01:33:08 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 01:33:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:33:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:33:08 INFO - Using real prefix '/tools/python27' 01:33:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:33:09 INFO - Installing distribute.............................................................................................................................................................................................done. 01:33:11 INFO - Installing pip.................done. 01:33:11 INFO - Return code: 0 01:33:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:33:12 INFO - Reading from file tmpfile_stdout 01:33:12 INFO - Output received: 01:33:12 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) 01:33:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:33:12 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:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:12 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:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:12 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:12 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 01:33:12 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 01:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:12 INFO - 'HOME': '/Users/cltbld', 01:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:12 INFO - 'LOGNAME': 'cltbld', 01:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:12 INFO - 'MOZ_NO_REMOTE': '1', 01:33:12 INFO - 'NO_EM_RESTART': '1', 01:33:12 INFO - 'PAGER': '/bin/cat', 01:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:12 INFO - 'PWD': '/builds/slave/test', 01:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:12 INFO - 'SHELL': '/bin/bash', 01:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:12 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:12 INFO - 'USER': 'cltbld', 01:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:12 INFO - 'XPC_FLAGS': '0x0', 01:33:12 INFO - 'XPC_SERVICE_NAME': '0', 01:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:12 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 01:33:12 INFO - Cleaning up... 01:33:12 INFO - Return code: 0 01:33:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:33:12 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:33:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:12 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:33:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:12 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:12 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 01:33:12 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 01:33:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:12 INFO - 'HOME': '/Users/cltbld', 01:33:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:12 INFO - 'LOGNAME': 'cltbld', 01:33:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:12 INFO - 'MOZ_NO_REMOTE': '1', 01:33:12 INFO - 'NO_EM_RESTART': '1', 01:33:12 INFO - 'PAGER': '/bin/cat', 01:33:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:12 INFO - 'PWD': '/builds/slave/test', 01:33:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:12 INFO - 'SHELL': '/bin/bash', 01:33:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:12 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:12 INFO - 'USER': 'cltbld', 01:33:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:12 INFO - 'XPC_FLAGS': '0x0', 01:33:12 INFO - 'XPC_SERVICE_NAME': '0', 01:33:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:12 INFO - Downloading/unpacking psutil>=3.1.1 01:33:12 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 01:33:12 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 01:33:12 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 01:33:12 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 01:33:12 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 01:33:12 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 01:33:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:33:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:33:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:33:15 INFO - Installing collected packages: psutil 01:33:15 INFO - Running setup.py install for psutil 01:33:15 INFO - building 'psutil._psutil_osx' extension 01:33:15 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 01:33:15 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 01:33:15 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 01:33:16 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 01:33:16 INFO - building 'psutil._psutil_posix' extension 01:33:16 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 01:33:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:33:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:33:16 INFO - ^ 01:33:16 INFO - 1 warning generated. 01:33:16 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 01:33:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:33:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:33:16 INFO - Successfully installed psutil 01:33:16 INFO - Cleaning up... 01:33:16 INFO - Return code: 0 01:33:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:33:16 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:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:16 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:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:16 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:16 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 01:33:16 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 01:33:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:16 INFO - 'HOME': '/Users/cltbld', 01:33:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:16 INFO - 'LOGNAME': 'cltbld', 01:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:16 INFO - 'MOZ_NO_REMOTE': '1', 01:33:16 INFO - 'NO_EM_RESTART': '1', 01:33:16 INFO - 'PAGER': '/bin/cat', 01:33:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:16 INFO - 'PWD': '/builds/slave/test', 01:33:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:16 INFO - 'SHELL': '/bin/bash', 01:33:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:16 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:16 INFO - 'USER': 'cltbld', 01:33:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:16 INFO - 'XPC_FLAGS': '0x0', 01:33:16 INFO - 'XPC_SERVICE_NAME': '0', 01:33:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:33:16 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 01:33:16 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 01:33:16 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 01:33:16 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 01:33:16 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 01:33:16 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 01:33:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:33:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:33:18 INFO - Installing collected packages: mozsystemmonitor 01:33:18 INFO - Running setup.py install for mozsystemmonitor 01:33:18 INFO - Successfully installed mozsystemmonitor 01:33:18 INFO - Cleaning up... 01:33:18 INFO - Return code: 0 01:33:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:33:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:33:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:33:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:18 INFO - 'HOME': '/Users/cltbld', 01:33:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:18 INFO - 'LOGNAME': 'cltbld', 01:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:18 INFO - 'MOZ_NO_REMOTE': '1', 01:33:18 INFO - 'NO_EM_RESTART': '1', 01:33:18 INFO - 'PAGER': '/bin/cat', 01:33:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:18 INFO - 'PWD': '/builds/slave/test', 01:33:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:18 INFO - 'SHELL': '/bin/bash', 01:33:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:18 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:18 INFO - 'USER': 'cltbld', 01:33:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:18 INFO - 'XPC_FLAGS': '0x0', 01:33:18 INFO - 'XPC_SERVICE_NAME': '0', 01:33:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:18 INFO - Downloading/unpacking jsonschema==2.5.1 01:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:33:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:20 INFO - Downloading functools32-3.2.3-2.tar.gz 01:33:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:33:20 INFO - warning: no files found matching '*.txt' 01:33:20 INFO - no previously-included directories found matching 'build' 01:33:20 INFO - no previously-included directories found matching 'dist' 01:33:20 INFO - no previously-included directories found matching '.git*' 01:33:20 INFO - Installing collected packages: jsonschema, functools32 01:33:20 INFO - Running setup.py install for functools32 01:33:20 INFO - warning: no files found matching '*.txt' 01:33:20 INFO - no previously-included directories found matching 'build' 01:33:20 INFO - no previously-included directories found matching 'dist' 01:33:20 INFO - no previously-included directories found matching '.git*' 01:33:20 INFO - Successfully installed jsonschema functools32 01:33:20 INFO - Cleaning up... 01:33:20 INFO - Return code: 0 01:33:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:33:20 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:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:20 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:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:20 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:20 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 01:33:20 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 01:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:20 INFO - 'HOME': '/Users/cltbld', 01:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:20 INFO - 'LOGNAME': 'cltbld', 01:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:20 INFO - 'MOZ_NO_REMOTE': '1', 01:33:20 INFO - 'NO_EM_RESTART': '1', 01:33:20 INFO - 'PAGER': '/bin/cat', 01:33:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:20 INFO - 'PWD': '/builds/slave/test', 01:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:20 INFO - 'SHELL': '/bin/bash', 01:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:20 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:20 INFO - 'USER': 'cltbld', 01:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:20 INFO - 'XPC_FLAGS': '0x0', 01:33:20 INFO - 'XPC_SERVICE_NAME': '0', 01:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:33:20 INFO - Cleaning up... 01:33:20 INFO - Return code: 0 01:33:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:33:20 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:33:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:20 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:33:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:20 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:20 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 01:33:20 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 01:33:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:20 INFO - 'HOME': '/Users/cltbld', 01:33:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:20 INFO - 'LOGNAME': 'cltbld', 01:33:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:20 INFO - 'MOZ_NO_REMOTE': '1', 01:33:20 INFO - 'NO_EM_RESTART': '1', 01:33:20 INFO - 'PAGER': '/bin/cat', 01:33:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:20 INFO - 'PWD': '/builds/slave/test', 01:33:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:20 INFO - 'SHELL': '/bin/bash', 01:33:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:20 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:20 INFO - 'USER': 'cltbld', 01:33:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:20 INFO - 'XPC_FLAGS': '0x0', 01:33:20 INFO - 'XPC_SERVICE_NAME': '0', 01:33:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:21 INFO - Downloading/unpacking blobuploader==1.2.4 01:33: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 01:33: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 01:33: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 01:33: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 01:33: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 01:33: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 01:33:22 INFO - Downloading blobuploader-1.2.4.tar.gz 01:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:33:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:33:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:33:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:33: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 01:33: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 01:33: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 01:33: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 01:33: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 01:33: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 01:33:23 INFO - Downloading docopt-0.6.1.tar.gz 01:33:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:33:23 INFO - Installing collected packages: blobuploader, requests, docopt 01:33:23 INFO - Running setup.py install for blobuploader 01:33:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:33:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:33:23 INFO - Running setup.py install for requests 01:33:24 INFO - Running setup.py install for docopt 01:33:24 INFO - Successfully installed blobuploader requests docopt 01:33:24 INFO - Cleaning up... 01:33:24 INFO - Return code: 0 01:33:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:33:24 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:33:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:24 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:33:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:24 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:24 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 01:33:24 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 01:33:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:24 INFO - 'HOME': '/Users/cltbld', 01:33:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:24 INFO - 'LOGNAME': 'cltbld', 01:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:24 INFO - 'MOZ_NO_REMOTE': '1', 01:33:24 INFO - 'NO_EM_RESTART': '1', 01:33:24 INFO - 'PAGER': '/bin/cat', 01:33:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:24 INFO - 'PWD': '/builds/slave/test', 01:33:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:24 INFO - 'SHELL': '/bin/bash', 01:33:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:24 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:24 INFO - 'USER': 'cltbld', 01:33:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:24 INFO - 'XPC_FLAGS': '0x0', 01:33:24 INFO - 'XPC_SERVICE_NAME': '0', 01:33:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:33:24 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-I7gAzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:33:24 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-MQSTiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:33:24 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-T_i_Ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:33:24 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-1ZllQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-gNMRxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-c4hDBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-upHJua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-lIZ6TH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-6iMcUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-vdN4SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ECEPaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-rfjTuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:33:25 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-RDKTtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:33:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-6pG72c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Vrdq67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:33:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-P63sj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:33:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:33:26 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ByqEeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:33:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:33:26 INFO - Running setup.py install for manifestparser 01:33:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:33:26 INFO - Running setup.py install for mozcrash 01:33:26 INFO - Running setup.py install for mozdebug 01:33:26 INFO - Running setup.py install for mozdevice 01:33:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Running setup.py install for mozfile 01:33:27 INFO - Running setup.py install for mozhttpd 01:33:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Running setup.py install for mozinfo 01:33:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Running setup.py install for mozInstall 01:33:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:33:27 INFO - Running setup.py install for mozleak 01:33:27 INFO - Running setup.py install for mozlog 01:33:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:33:28 INFO - Running setup.py install for moznetwork 01:33:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:33:28 INFO - Running setup.py install for mozprocess 01:33:28 INFO - Running setup.py install for mozprofile 01:33:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:33:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:33:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:33:28 INFO - Running setup.py install for mozrunner 01:33:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:33:28 INFO - Running setup.py install for mozscreenshot 01:33:28 INFO - Running setup.py install for moztest 01:33:28 INFO - Running setup.py install for mozversion 01:33:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:33:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:33:29 INFO - Cleaning up... 01:33:29 INFO - Return code: 0 01:33:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:33:29 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:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:29 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:33:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:29 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:29 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 01:33:29 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 01:33:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:29 INFO - 'HOME': '/Users/cltbld', 01:33:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:29 INFO - 'LOGNAME': 'cltbld', 01:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:29 INFO - 'MOZ_NO_REMOTE': '1', 01:33:29 INFO - 'NO_EM_RESTART': '1', 01:33:29 INFO - 'PAGER': '/bin/cat', 01:33:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:29 INFO - 'PWD': '/builds/slave/test', 01:33:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:29 INFO - 'SHELL': '/bin/bash', 01:33:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:29 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:29 INFO - 'USER': 'cltbld', 01:33:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:29 INFO - 'XPC_FLAGS': '0x0', 01:33:29 INFO - 'XPC_SERVICE_NAME': '0', 01:33:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:33:29 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Eee5M0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:33:29 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)) 01:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:33:29 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-bawROA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:33:29 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)) 01:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:33:29 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-PMA0k1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:33:29 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)) 01:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:33:29 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-j8EYYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:33:29 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)) 01:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:33:29 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-9uypiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:33:29 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)) 01:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:33:29 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-wSbN7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-H1gsKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Yj8LXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-EUlhfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-_9Jrt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-KaBQa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-CZ69W7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-fxoS7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-XDmnHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-auyqi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:33:30 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)) 01:33:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:33:30 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-t2cF2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:33:31 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)) 01:33:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:33:31 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-aCBo6t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:33:31 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)) 01:33:31 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)) 01:33:31 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)) 01:33:31 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)) 01:33:31 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)) 01:33:31 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)) 01:33:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:33:31 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 01:33:31 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 01:33:31 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 01:33:31 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 01:33:31 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 01:33:31 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 01:33:32 INFO - Downloading blessings-1.6.tar.gz 01:33:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:33:32 INFO - Installing collected packages: blessings 01:33:32 INFO - Running setup.py install for blessings 01:33:32 INFO - Successfully installed blessings 01:33:32 INFO - Cleaning up... 01:33:32 INFO - Return code: 0 01:33:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:33:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:33:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:33:33 INFO - Reading from file tmpfile_stdout 01:33:33 INFO - Current package versions: 01:33:33 INFO - blessings == 1.6 01:33:33 INFO - blobuploader == 1.2.4 01:33:33 INFO - docopt == 0.6.1 01:33:33 INFO - functools32 == 3.2.3-2 01:33:33 INFO - jsonschema == 2.5.1 01:33:33 INFO - manifestparser == 1.1 01:33:33 INFO - mozInstall == 1.12 01:33:33 INFO - mozcrash == 1.0 01:33:33 INFO - mozdebug == 0.1 01:33:33 INFO - mozdevice == 0.49 01:33:33 INFO - mozfile == 1.2 01:33:33 INFO - mozhttpd == 0.7 01:33:33 INFO - mozinfo == 0.9 01:33:33 INFO - mozleak == 0.1 01:33:33 INFO - mozlog == 3.4 01:33:33 INFO - moznetwork == 0.27 01:33:33 INFO - mozprocess == 0.25 01:33:33 INFO - mozprofile == 0.28 01:33:33 INFO - mozrunner == 6.13 01:33:33 INFO - mozscreenshot == 0.1 01:33:33 INFO - mozsystemmonitor == 0.3 01:33:33 INFO - moztest == 0.8 01:33:33 INFO - mozversion == 1.4 01:33:33 INFO - psutil == 3.1.1 01:33:33 INFO - requests == 1.2.3 01:33:33 INFO - wsgiref == 0.1.2 01:33:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:33:33 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:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:33 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:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:33 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:33 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 01:33:33 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 01:33:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:33 INFO - 'HOME': '/Users/cltbld', 01:33:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:33 INFO - 'LOGNAME': 'cltbld', 01:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:33 INFO - 'MOZ_NO_REMOTE': '1', 01:33:33 INFO - 'NO_EM_RESTART': '1', 01:33:33 INFO - 'PAGER': '/bin/cat', 01:33:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:33 INFO - 'PWD': '/builds/slave/test', 01:33:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:33 INFO - 'SHELL': '/bin/bash', 01:33:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:33 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:33 INFO - 'USER': 'cltbld', 01:33:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:33 INFO - 'XPC_FLAGS': '0x0', 01:33:33 INFO - 'XPC_SERVICE_NAME': '0', 01:33:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 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)) 01:33:33 INFO - Cleaning up... 01:33:33 INFO - Return code: 0 01:33:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:33:33 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:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:33 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:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:33:33 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 0x106309b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10630c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04d5310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, '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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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 01:33:33 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 01:33:33 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 01:33:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:33 INFO - 'HOME': '/Users/cltbld', 01:33:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:33 INFO - 'LOGNAME': 'cltbld', 01:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:33 INFO - 'MOZ_NO_REMOTE': '1', 01:33:33 INFO - 'NO_EM_RESTART': '1', 01:33:33 INFO - 'PAGER': '/bin/cat', 01:33:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:33 INFO - 'PWD': '/builds/slave/test', 01:33:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:33 INFO - 'SHELL': '/bin/bash', 01:33:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:33 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:33 INFO - 'USER': 'cltbld', 01:33:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:33 INFO - 'XPC_FLAGS': '0x0', 01:33:33 INFO - 'XPC_SERVICE_NAME': '0', 01:33:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:33:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:33:33 INFO - Cleaning up... 01:33:33 INFO - Return code: 0 01:33:33 INFO - Running post-action listener: _resource_record_post_action 01:33:33 INFO - Running post-action listener: _start_resource_monitoring 01:33:33 INFO - Starting resource monitoring. 01:33:33 INFO - [mozharness: 2017-05-09 08:33:33.773540Z] Finished create-virtualenv step (success) 01:33:33 INFO - [mozharness: 2017-05-09 08:33:33.774537Z] Running install step. 01:33:33 INFO - Running pre-action listener: _resource_record_pre_action 01:33:33 INFO - Running main action method: install 01:33:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:33:34 INFO - Reading from file tmpfile_stdout 01:33:34 INFO - Detecting whether we're running mozinstall >=1.0... 01:33:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:33:34 INFO - Reading from file tmpfile_stdout 01:33:34 INFO - Output received: 01:33:34 INFO - Usage: mozinstall [options] installer 01:33:34 INFO - Options: 01:33:34 INFO - -h, --help show this help message and exit 01:33:34 INFO - -d DEST, --destination=DEST 01:33:34 INFO - Directory to install application into. [default: 01:33:34 INFO - "/builds/slave/test"] 01:33:34 INFO - --app=APP Application being installed. [default: firefox] 01:33:34 INFO - mkdir: /builds/slave/test/build/application 01:33:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:33:54 INFO - Reading from file tmpfile_stdout 01:33:54 INFO - Output received: 01:33:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:33:54 INFO - Running post-action listener: _resource_record_post_action 01:33:54 INFO - [mozharness: 2017-05-09 08:33:54.601924Z] Finished install step (success) 01:33:54 INFO - [mozharness: 2017-05-09 08:33:54.602083Z] Running run-tests step. 01:33:54 INFO - Running pre-action listener: _resource_record_pre_action 01:33:54 INFO - Running main action method: run_tests 01:33:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:33:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:33:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:33:54 INFO - 2017-05-09 01:33:54.616 screenresolution[2598:37083] starting screenresolution argv=screenresolution get 01:33:54 INFO - 2017-05-09 01:33:54.619 screenresolution[2598:37083] Display 0: 1600x1200x32@60 01:33:54 INFO - 2017-05-09 01:33:54.625 screenresolution[2599:37086] starting screenresolution argv=screenresolution list 01:33:54 INFO - Available Modes on Display 0 01:33:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:33:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:33:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:33:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:33:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:33:54 INFO - Intel Iris: 01:33:54 INFO - Chipset Model: Intel Iris 01:33:54 INFO - Type: GPU 01:33:54 INFO - Bus: Built-In 01:33:54 INFO - VRAM (Dynamic, Max): 1536 MB 01:33:54 INFO - Vendor: Intel (0x8086) 01:33:54 INFO - Device ID: 0x0a2e 01:33:54 INFO - Revision ID: 0x0009 01:33:54 INFO - Displays: 01:33:54 INFO - Display: 01:33:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:33:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:33:54 INFO - Display Serial Number: 1600x1200 60 01:33:54 INFO - Main Display: Yes 01:33:54 INFO - Mirror: Off 01:33:54 INFO - Online: Yes 01:33:54 INFO - Rotation: Supported 01:33:54 INFO - Return code: 0 01:33:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:33:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:33:54 INFO - Python 2.7.3 01:33:54 INFO - Return code: 0 01:33:54 INFO - grabbing minidump binary from tooltool 01:33:54 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:33:54 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 0x1060e7d30>, '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 0x10600edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1060dcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:33:54 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 01:33:54 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 01:33:54 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 01:33:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:33:54 INFO - Return code: 0 01:33:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:33:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:33:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:33:54 INFO - ENV: RUST_BACKTRACE is now 1 01:33:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:33:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:33:54 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/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 01:33:54 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/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0135 --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 01:33:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aI9IH9AAzb/Render', 01:33:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:33:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:33:54 INFO - 'HOME': '/Users/cltbld', 01:33:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:33:54 INFO - 'LOGNAME': 'cltbld', 01:33:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:33:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:33:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:33:54 INFO - 'MOZ_NO_REMOTE': '1', 01:33:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:33:54 INFO - 'NO_EM_RESTART': '1', 01:33:54 INFO - 'PAGER': '/bin/cat', 01:33:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:33:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:33:54 INFO - 'PWD': '/builds/slave/test', 01:33:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:33:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:33:54 INFO - 'RUST_BACKTRACE': '1', 01:33:54 INFO - 'SHELL': '/bin/bash', 01:33:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9YmfvOuJ0i/Listeners', 01:33:54 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 01:33:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:33:54 INFO - 'USER': 'cltbld', 01:33:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:33:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:33:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:33:54 INFO - 'XPC_FLAGS': '0x0', 01:33:54 INFO - 'XPC_SERVICE_NAME': '0', 01:33:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:33:54 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/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 01:33:55 INFO - mozversion application_buildid: 20170509004016 01:33:55 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:33:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:33:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:33:55 INFO - mozversion application_name: Firefox 01:33:55 INFO - mozversion application_remotingname: firefox-dev 01:33:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:33:55 INFO - mozversion application_vendor: Mozilla 01:33:55 INFO - mozversion application_version: 54.0a2 01:33:55 INFO - mozversion platform_buildid: 20170509004016 01:33:55 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:33:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:33:55 INFO - mozversion platform_version: 54.0a2 01:33:55 INFO - using testdate: 1494318835 01:33:55 INFO - actual date: 1494318835 01:33:55 INFO - starting webserver on 'localhost:60993' 01:33:55 INFO - SUITE-START | Running 2 tests 01:33:55 INFO - TEST-START | dromaeo_css 01:33:55 INFO - operating with platform_type : mac_ 01:33:55 INFO - Initialising browser for dromaeo_css test... 01:33:55 INFO - TEST-INFO | started process 2609 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpf0H3Ym/profile http://localhost:60993/getInfo.html) 01:33:57 INFO - PROCESS | 2609 | __metrics Screen width/height:1600/1200 01:33:57 INFO - PROCESS | 2609 | colorDepth:24 01:33:57 INFO - PROCESS | 2609 | Browser inner width/height: 1024/689 01:33:57 INFO - PROCESS | 2609 | __metrics 01:33:57 INFO - PROCESS | 2609 | 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" 01:33:57 INFO - PROCESS | 2609 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:33:58 INFO - TEST-INFO | 2609: exit 0 01:33:58 INFO - Browser initialized. 01:33:58 INFO - Running cycle 1/1 for dromaeo_css test... 01:33:58 INFO - TEST-INFO | started process 2612 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpf0H3Ym/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:34:10 INFO - PROCESS | 2612 | RSS: Main: 214622208 01:34:10 INFO - PROCESS | 2612 | 01:37:15 INFO - PROCESS | 2612 | Cycle 1(1): loaded http://localhost:60993/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60993/tests/dromaeo/cssquery-ext.html) 01:37:17 INFO - PROCESS | 2612 | RSS: Main: 353472512 01:37:17 INFO - PROCESS | 2612 | 01:39:00 INFO - PROCESS | 2612 | ************************* 01:39:00 INFO - PROCESS | 2612 | A coding exception was thrown and uncaught in a Task. 01:39:00 INFO - PROCESS | 2612 | 01:39:00 ERROR - PROCESS | 2612 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:00 INFO - PROCESS | 2612 | Full stack: 01:39:00 INFO - PROCESS | 2612 | ************************* 01:39:00 INFO - PROCESS | 2612 | ************************* 01:39:00 INFO - PROCESS | 2612 | A coding exception was thrown and uncaught in a Task. 01:39:00 INFO - PROCESS | 2612 | 01:39:00 ERROR - PROCESS | 2612 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:00 INFO - PROCESS | 2612 | Full stack: 01:39:00 INFO - PROCESS | 2612 | ************************* 01:39:00 INFO - PROCESS | 2612 | ************************* 01:39:00 INFO - PROCESS | 2612 | A coding exception was thrown and uncaught in a Task. 01:39:00 INFO - PROCESS | 2612 | 01:39:00 ERROR - PROCESS | 2612 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:00 INFO - PROCESS | 2612 | Full stack: 01:39:00 INFO - PROCESS | 2612 | ************************* 01:39:00 INFO - PROCESS | 2612 | ************************* 01:39:00 INFO - PROCESS | 2612 | A coding exception was thrown and uncaught in a Task. 01:39:00 INFO - PROCESS | 2612 | 01:39:00 ERROR - PROCESS | 2612 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:39:00 INFO - PROCESS | 2612 | Full stack: 01:39:00 INFO - PROCESS | 2612 | ************************* 01:40:19 INFO - PROCESS | 2612 | Cycle 1(1): loaded http://localhost:60993/tests/dromaeo/cssquery-ext.html (next: http://localhost:60993/tests/dromaeo/cssquery-jquery.html) 01:40:20 INFO - PROCESS | 2612 | RSS: Main: 351039488 01:40:20 INFO - PROCESS | 2612 | 01:43:23 INFO - PROCESS | 2612 | Cycle 1(1): loaded http://localhost:60993/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60993/tests/dromaeo/cssquery-mootools.html) 01:43:23 INFO - PROCESS | 2612 | RSS: Main: 326389760 01:43:23 INFO - PROCESS | 2612 | 01:46:26 INFO - PROCESS | 2612 | Cycle 1(1): loaded http://localhost:60993/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60993/tests/dromaeo/cssquery-prototype.html) 01:46:26 INFO - PROCESS | 2612 | RSS: Main: 334237696 01:46:26 INFO - PROCESS | 2612 | 01:49:30 INFO - PROCESS | 2612 | Cycle 1(1): loaded http://localhost:60993/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60993/tests/dromaeo/cssquery-yui.html) 01:49:30 INFO - PROCESS | 2612 | RSS: Main: 329064448 01:49:30 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | Cycle 1(1): loaded http://localhost:60993/tests/dromaeo/cssquery-yui.html (next: http://localhost:60993/tests/dromaeo/cssquery-dojo.html) 01:52:34 INFO - PROCESS | 2612 | RSS: Main: 376123392 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | __start_tp_report 01:52:34 INFO - PROCESS | 2612 | _x_x_mozilla_page_load 01:52:34 INFO - PROCESS | 2612 | _x_x_mozilla_page_load_details 01:52:34 INFO - PROCESS | 2612 | |i|pagename|runs| 01:52:34 INFO - PROCESS | 2612 | |0;dojo.html;3386.0688310714536;3397.3375191837595;3397.72818174546;3403.710684591808;3475.2701932594155;5902.527797776178;5905.822825315242;5906.5274778017765;5929.85175370616;5930.733117009734;5871.823845284637;5964.910526342108;5988.981873081576;5994.250718660168;6009.75960961561;4904.509549045102;4992.55215987365;5300.0459917214885;5310.70791106489;5397.946020539784;5249.8950020999555;5253.868653283679;5308.23030660554;5443.918341224885;5466.234727138204;5328.227407025998;5334.67991920486;5348.545373643245;5350.5184533391985;5352.384475785276;5335.866603334909;5348.946510534923;5349;5350.438203988564;5355.491228333302;5189.221616757516;5277.788888444457;5291.735413229323;5294.311739473882;5334.03987282289;5918.526517878559;5923.437273459014;5925.31858836235;5927.9703601481715;5946.762129514815;6291;6315.431611154972;6325.715342809584;6336.493080553545;6336.619802811846;6263.467605253513;6291.370862913718;6292.339304373067;6300.779472718433;6301.8109456716375;4732.313814496879;6071.362506936753;6092;6104.328523862371;6109.053096769963;5357.571394288487;6178.351273116348;6264.467520260738;6443.742250309935;6469.450096741735;6244.625322480666;6278.623282603058;6305.684715764285;6330.335314791972;6340.302566717657;6352.809415717536;6361.745530178741;6367.331430199855;6374.171357723466;6379.393957574023;5287.629865909349;6160.383961603893;6273.0904018917;6291.874162516724;6292.370762923762;4973.328600638867;6035.66803825785;6198.659073750987;6240.656763878031;6299.842503937438;5104.540591346797;5121;5130.410002849645;5148.794048238104;5160.7935682573025;4561.315802629632;4572.154151481986;4575.794089265991;4597.057603191338;4597.816087356535;5734.655920644691;5743.195952566644;5782.942170578241;5948.078047902582;5965.105234214902;6247.468965138014;6252.218472690914;6267.1539342189135;6312.179416675895;6318.304986451579;5970.104484327386;5990.430909063632;5994.820155395345;6028.367021462771;6048.425399587032;5564.137558678412;5612.045952188138;5618.129189975561;5618.775248990077;5621;6260.092286618324;6277.466415354564;6491.091247225487;6517.73929042833;6518.348165183444;5607.43925607436;6024.6745715942425;6348.523860710373;6361.045843123568;6365.427111559982;6498;6502.479801615954;6508.349165083454;6517.348265173445;6520.445762110274;6168.568200225941;6257;6304.054391841077;6313.684315784284;6319.557630965972;6166.969165154145;6192.0711893214575;6301.747930082745;6317.431431171217;6333.841653958688;6219.626822390671;6288.528360373082;6296.559240853279;6315.179026726496;6322.652254125883;6098.908516372347;6182.629042257479;6297.496200303873;6312.905306420499;6316.463100636313;6070.757169713162;6131.277598982041;6142.78500252489;6162.291336496181;6168.352323006199;5344.7707027384695;6221.2845522763655;6286.874262514632;6303.306636269915;6328.145700330397;5449.836504904859;6099.420555047263;6190.50475961913;6206.038064100072;6224.190855188796 01:52:34 INFO - PROCESS | 2612 | |1;ext.html;658.4732214228616;795.268353115134;817.8159914019344;846.3144852669338;889.5374405309235;27591.44817103659;39754.20491590167;40351.19297614053;40379.586714465;40457;41056.58943410573;41141.58858411415;41196.176076478456;41258;41310.96722581937;7378.262173782632;9204.12560806724;9373.718788436323;9380.155785979261;9391.95304023479;7393.630318484081;9446.81106377872;9534.713958581218;11348.48931798067;11349.773004539904;11218.607348742795;11218.943905280463;11237.60668376607;11243;11254.437278136102;7315.780526584211;11158.051565616932;11182.720431989184;11187.552497900075;11207.719807004849;7256;7293.562386256842;7302.415806735448;7305.817354566178;10613.681589552325;38415.231695366216;38592.80703596464;38725.80637096825;38782.030449238715;38812.41781373282;3799.4680744695765;3808.8857334280015;3851.499305090348;3902.921941561153;3904.375299951994;26621;26675.06637267686;27620.44759104827;28498.71501285002;28513.144605661862;2661.507621090085;3114.704103110201;3145.056483055097;3152.322250716084;3179.856906439216;332060.99999999756;332672;334272;336594;336962;51406.48593514092;51493;51517.484825152016;51591;51723;54013;54029.729851350494;54115.72942135264;54174.45825541694;54243.45756542384;14567.125972441687;14680.192589407685;14689.92655036718;14701.632459188391;14707.705845883022;10829.404382758867;11031.448427578589;12720.773864573663;12732;12740.235585864877;11571.537138514364;11669.708257293636;11900.702482437835;11928.34394108315;12054.638360849012;4746.406699162605;5581.860453488614;5611.691356975324;5654.943450565524;5657.915131273035;1076.7415820203096;1094.578587243917;1094.6059418609295;1105.1324710102692;1143.7026373142876;24707.135250266514;25900.481990360087;26914;27291.454170916866;27393.04124355638;1001.4191768774093;1157.5774842182543;1163.069544364522;1175.9005330016462;1199.892009719112;13888.652783680489;14216.720495155485;14300;14514.709705805824;14554.490592829201;439463;447015;449005;450391;451044;46314.30528542075;46372.7681361591;46376;46414.5358546417;46445.83885402825;1372.3138430784609;1431.4488921765196;1436.4613270023742;1437.7987081808765;1445.775904734768;24844.503109937337;25804;25825.09612163565;25828.096016639327;26026.73973260319;37796;37848.24303513969;37925;37992.620073798906;38123;415906;422631;422795;423921;425468;27006.32484187911;27341.726582733918;27486.45027099487;27702.861485692443;27714.722852771214;35171.648283516835;35956.101097472776;36035.63964360323;36162;36181.45727814137;25152.02238488976;25162.37094072663;25524.489510210064;26266.737332626428;26837.597436038086;25678.74321256838;25920;26429.60355594629;26736.331591710365;26853.597196041683;29905.401891962472;29957.951471698387;30201.848990754905;30528.542071868495;30639;21434.464138396666;23514;25591;26023.609645854947;26935.009227392573;20234;20554.869482178026;21196.364109076752;21197.89401052985;21229.575408491433 01:52:34 INFO - PROCESS | 2612 | |2;jquery.html;389.1244699426291;411.62747713319436;424.37404827878885;428.8656503548115;447.4853917994306;12663.113582049249;14334.498292559763;20244.797552024476;20365.89817050913;20859;21019.684704729403;21209.939503024863;21567.352979410643;21848.12607495698;22181.77818221817;6590.703418346162;6642.4353929916015;6787.796366109;6875.484338674594;6881.518293719441;6540.411362977331;6733.225679046924;6741.494387920901;6855.862882742342;6885.4491640668875;6830.248672646005;6835.931640683604;6838.658067096625;6839.247682754918;6852.965735171317;6502.089707440962;6695.196576410862;6773.830654233634;6774.661266936633;6788.422984046362;6570.244421891496;6578.375054369827;6584.341565843425;6591.901121483182;6768.086308348359;24703.62944555833;25169.62245566318;25172.24483265505;25453.236402907944;25477.872610637012;2699.1767510909167;2901.40521193157;2915.6938521455154;2948.189247956808;2966.1842993176833;15501.534953951399;15776.290066947015;16000.359985600562;16232.513024609281;16679.66640667192;1921.8846869187894;2028.7971202879742;2039.75522937247;2059.618970490464;2060.6187855246826;109822;170372;172627;174518;178123;19511.512212194524;19929.900350498443;19971.400857974284;20010.89994550047;20111.39665810028;31199.75200991935;31416.528752068738;31417.42912854348;31450;31477.685223147935;9917.603295868228;9965.651202207888;10164.898351016394;10177.69466915979;10368.688939331832;7636.160022397532;8441.366897482714;8479.89761331023;8619.267362274277;8636.913630863737;7471.3649339806725;8100.959495202605;9196.586153623102;9257.907420925705;9281.48951807657;3743.834518731646;3840.231953609289;3846.4614953906475;3860.7104467165077;3869.9806500967316;793.349453448167;804.4891493901415;809.3484744780391;817.840521098392;836.3727204596553;19136.521586960436;19144.61710765777;19170;19958.397197284765;20273.69589456159;821.7165078048064;823.3660081737148;844.5523872347666;844.8479273730665;851.1403482482614;9947.662299359645;10407;10445.32105413161;10468;10586.788264234672;727085;732114;746812;747868;767047;130260.33721196983;130955;142799;153177;160230.19884900734;1226.5339171115097;1246.140163287347;1253.4234252243887;1271.2309053022789;1272.611853384708;17604.50361719217;17721.734173987657;19137.712934306273;19443;19584.60830783405;51960.22059669183;55326.44673553374;60827.78344433175;61335.69332153311;61533;86559;87681.56159219163;87908;89508.5524572373;90720;9348.579313930753;10584;10654;11171.66485005451;11449.656510304705;11866.940665296619;12140.939295303468;12180.330081845583;12190.57332993341;12545.49818007306;9468.384555003902;10014.19886409071;10446.380810974311;10797.676069717922;10866.61966831157;9998.100170984646;10104.898951010397;10210.387376757419;10376.377417354983;10645.840312395476;9082.909170908471;11275.097992160443;11287.661370158909;11522;11548.01841843419;11369.090472762324;11401.657950261173;11460;11591.478383472595;11831.230969987268;8983.371164018718;10871.347719136877;11028.779424411307;11031.338119712844;11201.887981120084 01:52:34 INFO - PROCESS | 2612 | |3;mootools.html;1245.2528482910254;1360.5034162530667;1376.2224343246062;1556.5330400879734;1594.1232322222781;10651.627193048245;11234.382108984002;11246.775064498715;11259.774804503904;11286.661400157998;10629.202809789258;10919.181061420406;10944.124470042398;10973.945130274358;10994.835077473825;2306.3657494189106;2347.882605869708;2349.823763217757;2351.0478256306224;2351.200591798788;2256.4810093678466;2340.0639744102323;2356.858588484688;2357.6817129687443;2360.7403185649564;2286.4398222435443;2331.94170145746;2376.489054853206;2383.380321116505;2389.0205015943466;2301.551197516485;2347.7182738071456;2368.2658375903525;2373.5608912351186;2376.9286921392386;1932.371979106782;2326.8138548916045;2338.0998315648426;2338.8012018978407;2339.9298021059394;10824.567017319298;10949.945250273777;10951.014408703175;10953.890461095367;10976.670699878936;4748.5014073522125;5519.31008623922;5678.6024978251535;5685.40303268155;5690.658560486384;3286.2113092857544;3368.4947257911263;3493.580770307554;3550.0592343029116;3551.7691350062423;1135.6138912769616;1167.078008373384;1176.014474024293;1180.3744015671707;1199.4722322178213;15405.537833865003;15474.61313467161;15591.064536127755;15596.688066238725;15610.609734756608;4295.484541854998;4541.636669066466;4590.334401511762;4619.19164146273;4624.1445332613575;10270.024347686958;10891.83662245067;11513.539458421737;11548.422578871023;11580.305181689126;5604.915926261109;5607.943920560742;5608.495235428831;5612.326520817528;5617.157426385993;4458.621017213508;5031.82388616404;5073.8731531712;5129.563987061067;5141.5886729061585;3701.222743223899;3773.509443772292;3792.8482860685262;3817.7900215487857;3833.4058220975794;955.1976339874539;958.108958668445;973.4159504297506;975.6487664440796;977.1791694976254;815.0830315894618;820.5651004967377;902.4720538484928;918.5590916360051;928.4800511713327;6449.516286278548;6537.542372034007;6641.501887358468;6688.398044176048;6803.115594972622;883.5758835758871;1020.2705065877933;1038.5689938675366;1040.6461802987021;1046.8693810684442;4320.65434765218;4397.890052748707;4444.977775111104;4488.735701083983;4580.267157254823;13102.934485327512;13389.464421423228;13559.322033898266;13577.456901724017;13635.795463068062;10517.789644207072;13480.191188528719;13518.391672374766;13530.594082177551;13612.047156699135;9825.459599722084;10101.292909496411;10105.44420056904;10117.898821011842;10124.645637402655;6303.243610766737;6345.303246120288;6537.182852143482;6563.474922006132;6587.63767992765;11220.86841934516;11386.94306528462;11521.423928803359;11521.654350369503;11551.768964620493;11797.469113890067;11798;11825.70435739079;11849.585264515701;11882.58410955647;8761.430507017023;9068.773280668036;9323.114304141096;9325;9331.16019558243;10017.799644007226;10106.39361638304;10110;10265.486725663837;10293.74265643365;8397.370197235354;8631.654733810577;8752.256058235122;8814.427062240933;8832.160944710242;6985.196702379291;8377.246047855722;8544.786380340542;8557.486550806723;8596.269317108116;7201.13586369639;7232.674529646284;7322.487425880137;7365.558066516026;7369.557826530426;5756.769729210785;5775.768969241351;5899.351071382231;5909.645421274738;5926.436988486087;5576.163575463551;5613.915791263053;5805.448482394166;5966.642001479925;5967.224260846062 01:52:34 INFO - PROCESS | 2612 | |4;prototype.html;362.8566716147122;372.97762134271943;375.2026942746663;380.5319457067807;387.6259409669668;4118.9908472424295;4270.252705776492;4850.490698476658;4883.095592718838;4963.776630051647;4464.330350447426;4799.5680388765;4827.672390092726;4916.926246106311;4930.654854160211;3786.5834758176647;4255.340422234543;4274.850380236692;4284.200635732984;4350.521442641323;3666.578343490493;4305.827766889321;4327.502337231212;4356.411884395614;4372.73763574185;4182.289010868145;4319.740815551061;4340.739555626673;4353.5211126776185;4395.911941827639;3646.4348026055873;4248.362745588156;4269.722468039598;4308.138372325532;4372.628326592244;3531.3450578650973;3633.4913112164054;3799.392097264452;4038.2125485530332;4119.546849846514;4004.379321205218;4874.341963834872;4894.7063176209185;4898.7263311539255;4965.528274813887;4355.5208927018;4390.604845563883;5012.27322038306;5041.252517378038;5094.841198456943;4523.954760452386;5004;5247.449017853147;5375.11310633745;5469.632591852037;4145.274576949052;4334.068175342317;4582.747948862811;4697.577218050392;4708.964027913854;4881.682690625098;5535.418781027975;5596.804111856066;5649.017442702926;5715.514181294637;3613.1689711366535;3782.470454136368;4073.022474606073;4119.814608342632;4335.544767799335;3931.8230679619487;4267.637250833714;4273.136998211144;4538.72767633943;4655.301704744248;4614.884627884263;4716.457607375127;4742.312364707098;4832.879178020507;4867.515407800584;5307.787688492494;5318.787248510093;5334.386545547234;5351.866203344947;5423.29497165366;1496.7801241987745;1538.8766200673572;1675.7653928449784;1693.1872701103293;1710.8366310908452;1346.8047133165637;1368.117564171124;1389.8811456777391;1392.5335012770836;1413.9363728632236;4337.501187363494;4609.501911878653;4936.876578085505;4973.0107956817565;4988.226824842155;4508.526604706459;4625.421822272216;5093.745312734349;5107.438181799999;5152.948470515322;4594.517575654575;4643.953560464352;4892.804287828518;4909.26361045846;4940.654154209171;2489.2283392148493;2645.989048323288;2682.409869828635;2818.971810281912;2825.7033011533495;4835.355978967168;5175.068487672255;5339.786408543615;5420.701861397661;5593.468620481048;4889.022195560902;5241.034612020492;5438.945610543923;5582.553395728332;5653.886922261532;4846.878828029327;5046.949530504721;5583.35791383996;5690.032694441955;5721.342045664802;4558.954410455985;5340.252364669027;5510.834674959758;5559.860228653198;5582.051051321204;4596.816127355002;4979.655493016793;4981.252812078217;4999.999999999913;5309.787608495772;5252.868678283074;5641.617803638135;5674.687892165971;5677.460641239076;5705.400932902152;3864.85014713008;4100.560166700809;4104.2911678292185;4337.3927650129635;4355.477342718767;4586;4811.4707382188635;5315.920261196008;5378;5450.236966824568;4321.762303073361;4563.361129441814;4610.561996610317;5248;5348.491893270133;4553.927962776995;4881.463039065771;4991.47589503097;5147.3308469898675;5284;4547.248914406869;4750.596199323097;4877.3903262092235;5177;5180.430152683277;4945.109880221595;5279.656822306538;5293.920591191212;5300.761465734128;5350;4805.639577031807;4986.254810816185;5019.447860735389;5054.570361519312;5133.25469340426 01:52:34 INFO - PROCESS | 2612 | |5;yui.html;626.896562067259;723.9203687594363;764.1975925278459;772.7411317208735;868.9652413903444;15004.549863504099;15790.210489475508;15799.605009874758;16016.59958501038;16302.510924672279;13891.583252502416;14492.637684057878;15057;15261.6947661047;15276.388944442237;931.4876817750227;958.2621381536213;958.8082383523289;958.9808203835954;961.7980224152936;939.2204470289678;954.8949615542285;966.3042609321277;975.3903810118676;976.2677991506371;1008.7326858382535;1009.1018993096151;1009.9899001010006;1010.206987514801;1011.0344620887035;1003.7440452684592;1006.3358183598863;1006.5420233793607;1006.854006169105;1009.399407352624;746;748.4985060009807;748.8689479341093;749.5465243527642;749.7375918428561;15683.843161568353;16068.357265709357;16095.839041609552;16482.34070637173;16717.331306747714;11490.540378384854;13626.318684065856;13665.590032299047;14022.298885055707;14087.084339517898;12297.56958506457;13013.4305658078;13189.47242110324;13224;13361.532346367927;4522.135474978483;4635.745034023127;4653.906921861578;4687.9531204687855;4714.316424118484;109609.54684343102;114033;114260.28609570864;114401;115476;11461.598844040585;11480.860270546591;11636.418179091012;11709.238899471677;11744.882551174549;24373.878130609235;24408.26775196747;24413.755862441503;24416.511669766503;24502.26493205207;7695.6921723130445;7773.650185741655;7929;7991.280784729286;8168.42821002536;4324.481062272484;4402.273624851863;4435.800388982503;4447.132809102259;4520;3551.4140166872694;3567.661072198137;3650.1969566695284;3656.6160553142067;3662.5421822272215;594.7234535940809;630.0737915264554;632.0708558419117;633.7655067625009;634.0837489827247;682.4881338995754;684.3532861445985;692.5325405351367;712.3304094151481;730.1967835381039;9194.035815080377;9325.90674093264;10149.797004060025;10367.32612380193;10772.569097236023;677.6916502991226;708.2952462300044;710.8507213485123;716.6309350684402;729.7409419656052;4784.497627749106;4939.160342741742;4968.801247950041;4976.477469865685;5024.221245706848;82524;99245;99988;101453.49273253586;104650.47674761577;48103.59481091957;56013;82163.5891820537;87094.56452717696;87278.56360718155;74529;75019.62490187732;75898;77221.61389193131;77734.22265777383;9041.954790226007;10887;10927.672169834917;11052;11140.944295278472;49214.753926230136;50022.749886250334;50139.24791128063;50881.491185087674;51185.744071279405;63761.36238637554;63940;64870.35129648832;65147;65521;13168.80246796318;13254.138480998705;13661.795073073714;13780;13943.233122178377;14222.857771422567;14353.75234147724;14578.85421145775;14845.480408185662;14890.702185956437;13022.413991370222;13445.596632101053;13917.58247252584;13997.860021399656;14350.641233969234;12658.493660253855;13247.60900105475;13809.309534523434;13960.511382101984;14005.579832605039;12454;13288.26914519672;13301.46794128224;13541.322933853466;13868.511282991803;9890.851637225303;10108.747281317732;10160.491975401348;10477.842832357368;10509.474526273503;11178.161637877029;11261.66215013551;11292.43537823122;11351.637803463636;11361.147913906656 01:52:34 INFO - PROCESS | 2612 | __end_tp_report 01:52:34 INFO - PROCESS | 2612 | __start_cc_report 01:52:34 INFO - PROCESS | 2612 | _x_x_mozilla_cycle_collect,1464 01:52:34 INFO - PROCESS | 2612 | __end_cc_report 01:52:34 INFO - PROCESS | 2612 | __startTimestamp1494319954622__endTimestamp 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | ------- Summary: start ------- 01:52:34 INFO - PROCESS | 2612 | Number of tests: 6 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | [#0] dojo.html Cycles:165 Average:5842.57 Median:6098.91 stddev:651.22 (10.7%) stddev-sans-first:624.06 01:52:34 INFO - PROCESS | 2612 | Values: 3386.1 3397.3 3397.7 3403.7 3475.3 5902.5 5905.8 5906.5 5929.9 5930.7 5871.8 5964.9 5989.0 5994.3 6009.8 4904.5 4992.6 5300.0 5310.7 5397.9 5249.9 5253.9 5308.2 5443.9 5466.2 5328.2 5334.7 5348.5 5350.5 5352.4 5335.9 5348.9 5349.0 5350.4 5355.5 5189.2 5277.8 5291.7 5294.3 5334.0 5918.5 5923.4 5925.3 5928.0 5946.8 6291.0 6315.4 6325.7 6336.5 6336.6 6263.5 6291.4 6292.3 6300.8 6301.8 4732.3 6071.4 6092.0 6104.3 6109.1 5357.6 6178.4 6264.5 6443.7 6469.5 6244.6 6278.6 6305.7 6330.3 6340.3 6352.8 6361.7 6367.3 6374.2 6379.4 5287.6 6160.4 6273.1 6291.9 6292.4 4973.3 6035.7 6198.7 6240.7 6299.8 5104.5 5121.0 5130.4 5148.8 5160.8 4561.3 4572.2 4575.8 4597.1 4597.8 5734.7 5743.2 5782.9 5948.1 5965.1 6247.5 6252.2 6267.2 6312.2 6318.3 5970.1 5990.4 5994.8 6028.4 6048.4 5564.1 5612.0 5618.1 5618.8 5621.0 6260.1 6277.5 6491.1 6517.7 6518.3 5607.4 6024.7 6348.5 6361.0 6365.4 6498.0 6502.5 6508.3 6517.3 6520.4 6168.6 6257.0 6304.1 6313.7 6319.6 6167.0 6192.1 6301.7 6317.4 6333.8 6219.6 6288.5 6296.6 6315.2 6322.7 6098.9 6182.6 6297.5 6312.9 6316.5 6070.8 6131.3 6142.8 6162.3 6168.4 5344.8 6221.3 6286.9 6303.3 6328.1 5449.8 6099.4 6190.5 6206.0 6224.2 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | [#1] ext.html Cycles:180 Average:52793.24 Median:30079.90 stddev:107342.15 (356.9%) stddev-sans-first:107571.90 01:52:34 INFO - PROCESS | 2612 | Values: 658.5 795.3 817.8 846.3 889.5 27591.4 39754.2 40351.2 40379.6 40457.0 41056.6 41141.6 41196.2 41258.0 41311.0 7378.3 9204.1 9373.7 9380.2 9392.0 7393.6 9446.8 9534.7 11348.5 11349.8 11218.6 11218.9 11237.6 11243.0 11254.4 7315.8 11158.1 11182.7 11187.6 11207.7 7256.0 7293.6 7302.4 7305.8 10613.7 38415.2 38592.8 38725.8 38782.0 38812.4 3799.5 3808.9 3851.5 3902.9 3904.4 26621.0 26675.1 27620.4 28498.7 28513.1 2661.5 3114.7 3145.1 3152.3 3179.9 332061.0 332672.0 334272.0 336594.0 336962.0 51406.5 51493.0 51517.5 51591.0 51723.0 54013.0 54029.7 54115.7 54174.5 54243.5 14567.1 14680.2 14689.9 14701.6 14707.7 10829.4 11031.4 12720.8 12732.0 12740.2 11571.5 11669.7 11900.7 11928.3 12054.6 4746.4 5581.9 5611.7 5654.9 5657.9 1076.7 1094.6 1094.6 1105.1 1143.7 24707.1 25900.5 26914.0 27291.5 27393.0 1001.4 1157.6 1163.1 1175.9 1199.9 13888.7 14216.7 14300.0 14514.7 14554.5 439463.0 447015.0 449005.0 450391.0 451044.0 46314.3 46372.8 46376.0 46414.5 46445.8 1372.3 1431.4 1436.5 1437.8 1445.8 24844.5 25804.0 25825.1 25828.1 26026.7 37796.0 37848.2 37925.0 37992.6 38123.0 415906.0 422631.0 422795.0 423921.0 425468.0 27006.3 27341.7 27486.5 27702.9 27714.7 35171.6 35956.1 36035.6 36162.0 36181.5 25152.0 25162.4 25524.5 26266.7 26837.6 25678.7 25920.0 26429.6 26736.3 26853.6 29905.4 29958.0 30201.8 30528.5 30639.0 21434.5 23514.0 25591.0 26023.6 26935.0 20234.0 20554.9 21196.4 21197.9 21229.6 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | [#2] jquery.html Cycles:180 Average:42367.22 Median:25465.55 stddev:124309.83 (488.1%) stddev-sans-first:124618.59 01:52:34 INFO - PROCESS | 2612 | Values: 389.1 411.6 424.4 428.9 447.5 12663.1 14334.5 20244.8 20365.9 20859.0 21019.7 21209.9 21567.4 21848.1 22181.8 6590.7 6642.4 6787.8 6875.5 6881.5 6540.4 6733.2 6741.5 6855.9 6885.4 6830.2 6835.9 6838.7 6839.2 6853.0 6502.1 6695.2 6773.8 6774.7 6788.4 6570.2 6578.4 6584.3 6591.9 6768.1 24703.6 25169.6 25172.2 25453.2 25477.9 2699.2 2901.4 2915.7 2948.2 2966.2 15501.5 15776.3 16000.4 16232.5 16679.7 1921.9 2028.8 2039.8 2059.6 2060.6 109822.0 170372.0 172627.0 174518.0 178123.0 19511.5 19929.9 19971.4 20010.9 20111.4 31199.8 31416.5 31417.4 31450.0 31477.7 9917.6 9965.7 10164.9 10177.7 10368.7 7636.2 8441.4 8479.9 8619.3 8636.9 7471.4 8101.0 9196.6 9257.9 9281.5 3743.8 3840.2 3846.5 3860.7 3870.0 793.3 804.5 809.3 817.8 836.4 19136.5 19144.6 19170.0 19958.4 20273.7 821.7 823.4 844.6 844.8 851.1 9947.7 10407.0 10445.3 10468.0 10586.8 727085.0 732114.0 746812.0 747868.0 767047.0 130260.3 130955.0 142799.0 153177.0 160230.2 1226.5 1246.1 1253.4 1271.2 1272.6 17604.5 17721.7 19137.7 19443.0 19584.6 51960.2 55326.4 60827.8 61335.7 61533.0 86559.0 87681.6 87908.0 89508.6 90720.0 9348.6 10584.0 10654.0 11171.7 11449.7 11866.9 12140.9 12180.3 12190.6 12545.5 9468.4 10014.2 10446.4 10797.7 10866.6 9998.1 10104.9 10210.4 10376.4 10645.8 9082.9 11275.1 11287.7 11522.0 11548.0 11369.1 11401.7 11460.0 11591.5 11831.2 8983.4 10871.3 11028.8 11031.3 11201.9 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | [#3] mootools.html Cycles:180 Average:6582.15 Median:2380.15 stddev:4140.49 (174.0%) stddev-sans-first:4132.68 01:52:34 INFO - PROCESS | 2612 | Values: 1245.3 1360.5 1376.2 1556.5 1594.1 10651.6 11234.4 11246.8 11259.8 11286.7 10629.2 10919.2 10944.1 10973.9 10994.8 2306.4 2347.9 2349.8 2351.0 2351.2 2256.5 2340.1 2356.9 2357.7 2360.7 2286.4 2331.9 2376.5 2383.4 2389.0 2301.6 2347.7 2368.3 2373.6 2376.9 1932.4 2326.8 2338.1 2338.8 2339.9 10824.6 10949.9 10951.0 10953.9 10976.7 4748.5 5519.3 5678.6 5685.4 5690.7 3286.2 3368.5 3493.6 3550.1 3551.8 1135.6 1167.1 1176.0 1180.4 1199.5 15405.5 15474.6 15591.1 15596.7 15610.6 4295.5 4541.6 4590.3 4619.2 4624.1 10270.0 10891.8 11513.5 11548.4 11580.3 5604.9 5607.9 5608.5 5612.3 5617.2 4458.6 5031.8 5073.9 5129.6 5141.6 3701.2 3773.5 3792.8 3817.8 3833.4 955.2 958.1 973.4 975.6 977.2 815.1 820.6 902.5 918.6 928.5 6449.5 6537.5 6641.5 6688.4 6803.1 883.6 1020.3 1038.6 1040.6 1046.9 4320.7 4397.9 4445.0 4488.7 4580.3 13102.9 13389.5 13559.3 13577.5 13635.8 10517.8 13480.2 13518.4 13530.6 13612.0 9825.5 10101.3 10105.4 10117.9 10124.6 6303.2 6345.3 6537.2 6563.5 6587.6 11220.9 11386.9 11521.4 11521.7 11551.8 11797.5 11798.0 11825.7 11849.6 11882.6 8761.4 9068.8 9323.1 9325.0 9331.2 10017.8 10106.4 10110.0 10265.5 10293.7 8397.4 8631.7 8752.3 8814.4 8832.2 6985.2 8377.2 8544.8 8557.5 8596.3 7201.1 7232.7 7322.5 7365.6 7369.6 5756.8 5775.8 5899.4 5909.6 5926.4 5576.2 5613.9 5805.4 5966.6 5967.2 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | [#4] prototype.html Cycles:180 Average:4437.41 Median:4802.60 stddev:1181.89 (24.6%) stddev-sans-first:1144.95 01:52:34 INFO - PROCESS | 2612 | Values: 362.9 373.0 375.2 380.5 387.6 4119.0 4270.3 4850.5 4883.1 4963.8 4464.3 4799.6 4827.7 4916.9 4930.7 3786.6 4255.3 4274.9 4284.2 4350.5 3666.6 4305.8 4327.5 4356.4 4372.7 4182.3 4319.7 4340.7 4353.5 4395.9 3646.4 4248.4 4269.7 4308.1 4372.6 3531.3 3633.5 3799.4 4038.2 4119.5 4004.4 4874.3 4894.7 4898.7 4965.5 4355.5 4390.6 5012.3 5041.3 5094.8 4524.0 5004.0 5247.4 5375.1 5469.6 4145.3 4334.1 4582.7 4697.6 4709.0 4881.7 5535.4 5596.8 5649.0 5715.5 3613.2 3782.5 4073.0 4119.8 4335.5 3931.8 4267.6 4273.1 4538.7 4655.3 4614.9 4716.5 4742.3 4832.9 4867.5 5307.8 5318.8 5334.4 5351.9 5423.3 1496.8 1538.9 1675.8 1693.2 1710.8 1346.8 1368.1 1389.9 1392.5 1413.9 4337.5 4609.5 4936.9 4973.0 4988.2 4508.5 4625.4 5093.7 5107.4 5152.9 4594.5 4644.0 4892.8 4909.3 4940.7 2489.2 2646.0 2682.4 2819.0 2825.7 4835.4 5175.1 5339.8 5420.7 5593.5 4889.0 5241.0 5438.9 5582.6 5653.9 4846.9 5046.9 5583.4 5690.0 5721.3 4559.0 5340.3 5510.8 5559.9 5582.1 4596.8 4979.7 4981.3 5000.0 5309.8 5252.9 5641.6 5674.7 5677.5 5705.4 3864.9 4100.6 4104.3 4337.4 4355.5 4586.0 4811.5 5315.9 5378.0 5450.2 4321.8 4563.4 4610.6 5248.0 5348.5 4553.9 4881.5 4991.5 5147.3 5284.0 4547.2 4750.6 4877.4 5177.0 5180.4 4945.1 5279.7 5293.9 5300.8 5350.0 4805.6 4986.3 5019.4 5054.6 5133.3 01:52:34 INFO - PROCESS | 2612 | 01:52:34 INFO - PROCESS | 2612 | [#5] yui.html Cycles:180 Average:20202.68 Median:24415.13 stddev:28518.85 (116.8%) stddev-sans-first:28560.98 01:52:34 INFO - PROCESS | 2612 | Values: 626.9 723.9 764.2 772.7 869.0 15004.5 15790.2 15799.6 16016.6 16302.5 13891.6 14492.6 15057.0 15261.7 15276.4 931.5 958.3 958.8 959.0 961.8 939.2 954.9 966.3 975.4 976.3 1008.7 1009.1 1010.0 1010.2 1011.0 1003.7 1006.3 1006.5 1006.9 1009.4 746.0 748.5 748.9 749.5 749.7 15683.8 16068.4 16095.8 16482.3 16717.3 11490.5 13626.3 13665.6 14022.3 14087.1 12297.6 13013.4 13189.5 13224.0 13361.5 4522.1 4635.7 4653.9 4688.0 4714.3 109609.5 114033.0 114260.3 114401.0 115476.0 11461.6 11480.9 11636.4 11709.2 11744.9 24373.9 24408.3 24413.8 24416.5 24502.3 7695.7 7773.7 7929.0 7991.3 8168.4 4324.5 4402.3 4435.8 4447.1 4520.0 3551.4 3567.7 3650.2 3656.6 3662.5 594.7 630.1 632.1 633.8 634.1 682.5 684.4 692.5 712.3 730.2 9194.0 9325.9 10149.8 10367.3 10772.6 677.7 708.3 710.9 716.6 729.7 4784.5 4939.2 4968.8 4976.5 5024.2 82524.0 99245.0 99988.0 101453.5 104650.5 48103.6 56013.0 82163.6 87094.6 87278.6 74529.0 75019.6 75898.0 77221.6 77734.2 9042.0 10887.0 10927.7 11052.0 11140.9 49214.8 50022.7 50139.2 50881.5 51185.7 63761.4 63940.0 64870.4 65147.0 65521.0 13168.8 13254.1 13661.8 13780.0 13943.2 14222.9 14353.8 14578.9 14845.5 14890.7 13022.4 13445.6 13917.6 13997.9 14350.6 12658.5 13247.6 13809.3 13960.5 14005.6 12454.0 13288.3 13301.5 13541.3 13868.5 9890.9 10108.7 10160.5 10477.8 10509.5 11178.2 11261.7 11292.4 11351.6 11361.1 01:52:34 INFO - PROCESS | 2612 | -------- Summary: end -------- 01:52:34 INFO - PROCESS | 2612 | 01:52:35 INFO - TEST-INFO | 2612: exit 0 01:52:35 INFO - TEST-OK | dromaeo_css | took 1120160ms 01:52:35 INFO - TEST-START | kraken 01:52:35 INFO - operating with platform_type : mac_ 01:52:35 INFO - Initialising browser for kraken test... 01:52:35 INFO - TEST-INFO | started process 2617 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmplnIGTp/profile http://localhost:60993/getInfo.html) 01:52:37 INFO - PROCESS | 2617 | __metrics Screen width/height:1600/1200 01:52:37 INFO - PROCESS | 2617 | colorDepth:24 01:52:37 INFO - PROCESS | 2617 | Browser inner width/height: 1024/689 01:52:37 INFO - PROCESS | 2617 | __metrics 01:52:37 INFO - PROCESS | 2617 | 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" 01:52:37 INFO - PROCESS | 2617 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:52:38 INFO - TEST-INFO | 2617: exit 0 01:52:38 INFO - Browser initialized. 01:52:38 INFO - Running cycle 1/1 for kraken test... 01:52:38 INFO - TEST-INFO | started process 2619 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmplnIGTp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:52:50 INFO - PROCESS | 2619 | RSS: Main: 211058688 01:52:50 INFO - PROCESS | 2619 | 01:52:53 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60993/tests/kraken/driver.html?testName=audio-beat-detection) 01:52:54 INFO - PROCESS | 2619 | RSS: Main: 264232960 01:52:54 INFO - PROCESS | 2619 | 01:52:57 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60993/tests/kraken/driver.html?testName=audio-dft) 01:52:57 INFO - PROCESS | 2619 | RSS: Main: 277798912 01:52:57 INFO - PROCESS | 2619 | 01:53:00 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60993/tests/kraken/driver.html?testName=audio-fft) 01:53:01 INFO - PROCESS | 2619 | RSS: Main: 375701504 01:53:01 INFO - PROCESS | 2619 | 01:53:03 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60993/tests/kraken/driver.html?testName=audio-oscillator) 01:53:04 INFO - PROCESS | 2619 | RSS: Main: 277659648 01:53:04 INFO - PROCESS | 2619 | 01:53:06 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60993/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:53:06 INFO - PROCESS | 2619 | RSS: Main: 279584768 01:53:06 INFO - PROCESS | 2619 | 01:53:09 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60993/tests/kraken/driver.html?testName=imaging-darkroom) 01:53:10 INFO - PROCESS | 2619 | RSS: Main: 320094208 01:53:10 INFO - PROCESS | 2619 | 01:53:13 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60993/tests/kraken/driver.html?testName=imaging-desaturate) 01:53:14 INFO - PROCESS | 2619 | RSS: Main: 320425984 01:53:14 INFO - PROCESS | 2619 | 01:53:16 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60993/tests/kraken/driver.html?testName=json-parse-financial) 01:53:17 INFO - PROCESS | 2619 | RSS: Main: 317550592 01:53:17 INFO - PROCESS | 2619 | 01:53:19 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60993/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:53:19 INFO - PROCESS | 2619 | RSS: Main: 296013824 01:53:19 INFO - PROCESS | 2619 | 01:53:21 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:53:22 INFO - PROCESS | 2619 | RSS: Main: 302886912 01:53:22 INFO - PROCESS | 2619 | 01:53:24 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:53:25 INFO - PROCESS | 2619 | RSS: Main: 305803264 01:53:25 INFO - PROCESS | 2619 | 01:53:28 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:53:28 INFO - PROCESS | 2619 | RSS: Main: 310136832 01:53:28 INFO - PROCESS | 2619 | 01:53:31 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:53:32 INFO - PROCESS | 2619 | RSS: Main: 311234560 01:53:32 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | Cycle 1(1): loaded http://localhost:60993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60993/tests/kraken/driver.html?testName=ai-astar) 01:53:34 INFO - PROCESS | 2619 | RSS: Main: 355557376 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | __start_tp_report 01:53:34 INFO - PROCESS | 2619 | _x_x_mozilla_page_load 01:53:34 INFO - PROCESS | 2619 | _x_x_mozilla_page_load_details 01:53:34 INFO - PROCESS | 2619 | |i|pagename|runs| 01:53:34 INFO - PROCESS | 2619 | |0;ai-astar;105;117;117;115;116;117;117;116;116;117 01:53:34 INFO - PROCESS | 2619 | |1;audio-beat-detection;145;110;110;146;111;111;108;104;123;130 01:53:34 INFO - PROCESS | 2619 | |2;audio-dft;183;183;184;184;182;185;186;185;186;183 01:53:34 INFO - PROCESS | 2619 | |3;audio-fft;82;59;58;96;63;61;60;75;98;59 01:53:34 INFO - PROCESS | 2619 | |4;audio-oscillator;89;69;87;69;69;68;83;70;69;83 01:53:34 INFO - PROCESS | 2619 | |5;imaging-gaussian-blur;93;134;88;88;92;88;90;89;89;87 01:53:34 INFO - PROCESS | 2619 | |6;imaging-darkroom;94;94;94;95;94;94;95;94;95;94 01:53:34 INFO - PROCESS | 2619 | |7;imaging-desaturate;84;90;129;114;84;86;84;85;83;84 01:53:34 INFO - PROCESS | 2619 | |8;json-parse-financial;62;61;59;60;66;62;63;63;64;64 01:53:34 INFO - PROCESS | 2619 | |9;json-stringify-tinderbox;54;66;65;63;61;58;58;60;57;59 01:53:34 INFO - PROCESS | 2619 | |10;stanford-crypto-aes;86;82;86;81;83;84;84;86;87;121 01:53:34 INFO - PROCESS | 2619 | |11;stanford-crypto-ccm;138;138;138;161;124;135;132;137;161;153 01:53:34 INFO - PROCESS | 2619 | |12;stanford-crypto-pbkdf2;164;171;170;165;164;165;165;163;149;221 01:53:34 INFO - PROCESS | 2619 | |13;stanford-crypto-sha256-iterative;66;66;65;65;66;70;81;82;79;80 01:53:34 INFO - PROCESS | 2619 | __end_tp_report 01:53:34 INFO - PROCESS | 2619 | __start_cc_report 01:53:34 INFO - PROCESS | 2619 | _x_x_mozilla_cycle_collect,2092 01:53:34 INFO - PROCESS | 2619 | __end_cc_report 01:53:34 INFO - PROCESS | 2619 | __startTimestamp1494320014425__endTimestamp 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | ------- Summary: start ------- 01:53:34 INFO - PROCESS | 2619 | Number of tests: 14 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#0] ai-astar Cycles:10 Average:115.30 Median:117.00 stddev:3.68 (3.1%) stddev-sans-first:0.73 01:53:34 INFO - PROCESS | 2619 | Values: 105.0 117.0 117.0 115.0 116.0 117.0 117.0 116.0 116.0 117.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:117.00 stddev:15.53 (13.3%) stddev-sans-first:13.54 01:53:34 INFO - PROCESS | 2619 | Values: 145.0 110.0 110.0 146.0 111.0 111.0 108.0 104.0 123.0 130.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:1.37 (0.7%) stddev-sans-first:1.39 01:53:34 INFO - PROCESS | 2619 | Values: 183.0 183.0 184.0 184.0 182.0 185.0 186.0 185.0 186.0 183.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#3] audio-fft Cycles:10 Average:71.10 Median:69.00 stddev:15.75 (22.8%) stddev-sans-first:16.21 01:53:34 INFO - PROCESS | 2619 | Values: 82.0 59.0 58.0 96.0 63.0 61.0 60.0 75.0 98.0 59.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.50 stddev:8.71 (11.4%) stddev-sans-first:7.77 01:53:34 INFO - PROCESS | 2619 | Values: 89.0 69.0 87.0 69.0 69.0 68.0 83.0 70.0 69.0 83.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:89.00 stddev:14.25 (16.0%) stddev-sans-first:15.11 01:53:34 INFO - PROCESS | 2619 | Values: 93.0 134.0 88.0 88.0 92.0 88.0 90.0 89.0 89.0 87.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 01:53:34 INFO - PROCESS | 2619 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:84.00 stddev:15.91 (18.9%) stddev-sans-first:16.59 01:53:34 INFO - PROCESS | 2619 | Values: 84.0 90.0 129.0 114.0 84.0 86.0 84.0 85.0 83.0 84.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:63.00 stddev:2.07 (3.3%) stddev-sans-first:2.19 01:53:34 INFO - PROCESS | 2619 | Values: 62.0 61.0 59.0 60.0 66.0 62.0 63.0 63.0 64.0 64.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.50 stddev:3.73 (6.2%) stddev-sans-first:3.23 01:53:34 INFO - PROCESS | 2619 | Values: 54.0 66.0 65.0 63.0 61.0 58.0 58.0 60.0 57.0 59.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:85.00 stddev:11.76 (13.8%) stddev-sans-first:12.45 01:53:34 INFO - PROCESS | 2619 | Values: 86.0 82.0 86.0 81.0 83.0 84.0 84.0 86.0 87.0 121.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:138.00 stddev:12.42 (9.0%) stddev-sans-first:13.10 01:53:34 INFO - PROCESS | 2619 | Values: 138.0 138.0 138.0 161.0 124.0 135.0 132.0 137.0 161.0 153.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:165.00 stddev:18.97 (11.5%) stddev-sans-first:20.01 01:53:34 INFO - PROCESS | 2619 | Values: 164.0 171.0 170.0 165.0 164.0 165.0 165.0 163.0 149.0 221.0 01:53:34 INFO - PROCESS | 2619 | 01:53:34 INFO - PROCESS | 2619 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:74.50 stddev:7.48 (10.0%) stddev-sans-first:7.62 01:53:34 INFO - PROCESS | 2619 | Values: 66.0 66.0 65.0 65.0 66.0 70.0 81.0 82.0 79.0 80.0 01:53:34 INFO - PROCESS | 2619 | -------- Summary: end -------- 01:53:34 INFO - PROCESS | 2619 | 01:53:35 INFO - TEST-INFO | 2619: exit 0 01:53:35 INFO - TEST-OK | kraken | took 59988ms 01:53:35 INFO - SUITE-END | took 1180s 01:53:35 INFO - Completed test suite (00:19:40) 01:53:35 INFO - javascript benchmark 01:53:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7530.055674585987, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.0688310714536, 3397.3375191837595, 3397.72818174546, 3403.710684591808, 3475.2701932594155, 5902.527797776178, 5905.822825315242, 5906.5274778017765, 5929.85175370616, 5930.733117009734, 5871.823845284637, 5964.910526342108, 5988.981873081576, 5994.250718660168, 6009.75960961561, 4904.509549045102, 4992.55215987365, 5300.0459917214885, 5310.70791106489, 5397.946020539784, 5249.8950020999555, 5253.868653283679, 5308.23030660554, 5443.918341224885, 5466.234727138204, 5328.227407025998, 5334.67991920486, 5348.545373643245, 5350.5184533391985, 5352.384475785276, 5335.866603334909, 5348.946510534923, 5349.0, 5350.438203988564, 5355.491228333302, 5189.221616757516, 5277.788888444457, 5291.735413229323, 5294.311739473882, 5334.03987282289, 5918.526517878559, 5923.437273459014, 5925.31858836235, 5927.9703601481715, 5946.762129514815, 6291.0, 6315.431611154972, 6325.715342809584, 6336.493080553545, 6336.619802811846, 6263.467605253513, 6291.370862913718, 6292.339304373067, 6300.779472718433, 6301.8109456716375, 4732.313814496879, 6071.362506936753, 6092.0, 6104.328523862371, 6109.053096769963, 5357.571394288487, 6178.351273116348, 6264.467520260738, 6443.742250309935, 6469.450096741735, 6244.625322480666, 6278.623282603058, 6305.684715764285, 6330.335314791972, 6340.302566717657, 6352.809415717536, 6361.745530178741, 6367.331430199855, 6374.171357723466, 6379.393957574023, 5287.629865909349, 6160.383961603893, 6273.0904018917, 6291.874162516724, 6292.370762923762, 4973.328600638867, 6035.66803825785, 6198.659073750987, 6240.656763878031, 6299.842503937438, 5104.540591346797, 5121.0, 5130.410002849645, 5148.794048238104, 5160.7935682573025, 4561.315802629632, 4572.154151481986, 4575.794089265991, 4597.057603191338, 4597.816087356535, 5734.655920644691, 5743.195952566644, 5782.942170578241, 5948.078047902582, 5965.105234214902, 6247.468965138014, 6252.218472690914, 6267.1539342189135, 6312.179416675895, 6318.304986451579, 5970.104484327386, 5990.430909063632, 5994.820155395345, 6028.367021462771, 6048.425399587032, 5564.137558678412, 5612.045952188138, 5618.129189975561, 5618.775248990077, 5621.0, 6260.092286618324, 6277.466415354564, 6491.091247225487, 6517.73929042833, 6518.348165183444, 5607.43925607436, 6024.6745715942425, 6348.523860710373, 6361.045843123568, 6365.427111559982, 6498.0, 6502.479801615954, 6508.349165083454, 6517.348265173445, 6520.445762110274, 6168.568200225941, 6257.0, 6304.054391841077, 6313.684315784284, 6319.557630965972, 6166.969165154145, 6192.0711893214575, 6301.747930082745, 6317.431431171217, 6333.841653958688, 6219.626822390671, 6288.528360373082, 6296.559240853279, 6315.179026726496, 6322.652254125883, 6098.908516372347, 6182.629042257479, 6297.496200303873, 6312.905306420499, 6316.463100636313, 6070.757169713162, 6131.277598982041, 6142.78500252489, 6162.291336496181, 6168.352323006199, 5344.7707027384695, 6221.2845522763655, 6286.874262514632, 6303.306636269915, 6328.145700330397, 5449.836504904859, 6099.420555047263, 6190.50475961913, 6206.038064100072, 6224.190855188796], "value": 5802.9985711315785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.4732214228616, 795.268353115134, 817.8159914019344, 846.3144852669338, 889.5374405309235, 27591.44817103659, 39754.20491590167, 40351.19297614053, 40379.586714465, 40457.0, 41056.58943410573, 41141.58858411415, 41196.176076478456, 41258.0, 41310.96722581937, 7378.262173782632, 9204.12560806724, 9373.718788436323, 9380.155785979261, 9391.95304023479, 7393.630318484081, 9446.81106377872, 9534.713958581218, 11348.48931798067, 11349.773004539904, 11218.607348742795, 11218.943905280463, 11237.60668376607, 11243.0, 11254.437278136102, 7315.780526584211, 11158.051565616932, 11182.720431989184, 11187.552497900075, 11207.719807004849, 7256.0, 7293.562386256842, 7302.415806735448, 7305.817354566178, 10613.681589552325, 38415.231695366216, 38592.80703596464, 38725.80637096825, 38782.030449238715, 38812.41781373282, 3799.4680744695765, 3808.8857334280015, 3851.499305090348, 3902.921941561153, 3904.375299951994, 26621.0, 26675.06637267686, 27620.44759104827, 28498.71501285002, 28513.144605661862, 2661.507621090085, 3114.704103110201, 3145.056483055097, 3152.322250716084, 3179.856906439216, 332060.99999999756, 332672.0, 334272.0, 336594.0, 336962.0, 51406.48593514092, 51493.0, 51517.484825152016, 51591.0, 51723.0, 54013.0, 54029.729851350494, 54115.72942135264, 54174.45825541694, 54243.45756542384, 14567.125972441687, 14680.192589407685, 14689.92655036718, 14701.632459188391, 14707.705845883022, 10829.404382758867, 11031.448427578589, 12720.773864573663, 12732.0, 12740.235585864877, 11571.537138514364, 11669.708257293636, 11900.702482437835, 11928.34394108315, 12054.638360849012, 4746.406699162605, 5581.860453488614, 5611.691356975324, 5654.943450565524, 5657.915131273035, 1076.7415820203096, 1094.578587243917, 1094.6059418609295, 1105.1324710102692, 1143.7026373142876, 24707.135250266514, 25900.481990360087, 26914.0, 27291.454170916866, 27393.04124355638, 1001.4191768774093, 1157.5774842182543, 1163.069544364522, 1175.9005330016462, 1199.892009719112, 13888.652783680489, 14216.720495155485, 14300.0, 14514.709705805824, 14554.490592829201, 439463.0, 447015.0, 449005.0, 450391.0, 451044.0, 46314.30528542075, 46372.7681361591, 46376.0, 46414.5358546417, 46445.83885402825, 1372.3138430784609, 1431.4488921765196, 1436.4613270023742, 1437.7987081808765, 1445.775904734768, 24844.503109937337, 25804.0, 25825.09612163565, 25828.096016639327, 26026.73973260319, 37796.0, 37848.24303513969, 37925.0, 37992.620073798906, 38123.0, 415906.0, 422631.0, 422795.0, 423921.0, 425468.0, 27006.32484187911, 27341.726582733918, 27486.45027099487, 27702.861485692443, 27714.722852771214, 35171.648283516835, 35956.101097472776, 36035.63964360323, 36162.0, 36181.45727814137, 25152.02238488976, 25162.37094072663, 25524.489510210064, 26266.737332626428, 26837.597436038086, 25678.74321256838, 25920.0, 26429.60355594629, 26736.331591710365, 26853.597196041683, 29905.401891962472, 29957.951471698387, 30201.848990754905, 30528.542071868495, 30639.0, 21434.464138396666, 23514.0, 25591.0, 26023.609645854947, 26935.009227392573, 20234.0, 20554.869482178026, 21196.364109076752, 21197.89401052985, 21229.575408491433], "value": 17683.36289194175, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.1244699426291, 411.62747713319436, 424.37404827878885, 428.8656503548115, 447.4853917994306, 12663.113582049249, 14334.498292559763, 20244.797552024476, 20365.89817050913, 20859.0, 21019.684704729403, 21209.939503024863, 21567.352979410643, 21848.12607495698, 22181.77818221817, 6590.703418346162, 6642.4353929916015, 6787.796366109, 6875.484338674594, 6881.518293719441, 6540.411362977331, 6733.225679046924, 6741.494387920901, 6855.862882742342, 6885.4491640668875, 6830.248672646005, 6835.931640683604, 6838.658067096625, 6839.247682754918, 6852.965735171317, 6502.089707440962, 6695.196576410862, 6773.830654233634, 6774.661266936633, 6788.422984046362, 6570.244421891496, 6578.375054369827, 6584.341565843425, 6591.901121483182, 6768.086308348359, 24703.62944555833, 25169.62245566318, 25172.24483265505, 25453.236402907944, 25477.872610637012, 2699.1767510909167, 2901.40521193157, 2915.6938521455154, 2948.189247956808, 2966.1842993176833, 15501.534953951399, 15776.290066947015, 16000.359985600562, 16232.513024609281, 16679.66640667192, 1921.8846869187894, 2028.7971202879742, 2039.75522937247, 2059.618970490464, 2060.6187855246826, 109822.0, 170372.0, 172627.0, 174518.0, 178123.0, 19511.512212194524, 19929.900350498443, 19971.400857974284, 20010.89994550047, 20111.39665810028, 31199.75200991935, 31416.528752068738, 31417.42912854348, 31450.0, 31477.685223147935, 9917.603295868228, 9965.651202207888, 10164.898351016394, 10177.69466915979, 10368.688939331832, 7636.160022397532, 8441.366897482714, 8479.89761331023, 8619.267362274277, 8636.913630863737, 7471.3649339806725, 8100.959495202605, 9196.586153623102, 9257.907420925705, 9281.48951807657, 3743.834518731646, 3840.231953609289, 3846.4614953906475, 3860.7104467165077, 3869.9806500967316, 793.349453448167, 804.4891493901415, 809.3484744780391, 817.840521098392, 836.3727204596553, 19136.521586960436, 19144.61710765777, 19170.0, 19958.397197284765, 20273.69589456159, 821.7165078048064, 823.3660081737148, 844.5523872347666, 844.8479273730665, 851.1403482482614, 9947.662299359645, 10407.0, 10445.32105413161, 10468.0, 10586.788264234672, 727085.0, 732114.0, 746812.0, 747868.0, 767047.0, 130260.33721196983, 130955.0, 142799.0, 153177.0, 160230.19884900734, 1226.5339171115097, 1246.140163287347, 1253.4234252243887, 1271.2309053022789, 1272.611853384708, 17604.50361719217, 17721.734173987657, 19137.712934306273, 19443.0, 19584.60830783405, 51960.22059669183, 55326.44673553374, 60827.78344433175, 61335.69332153311, 61533.0, 86559.0, 87681.56159219163, 87908.0, 89508.5524572373, 90720.0, 9348.579313930753, 10584.0, 10654.0, 11171.66485005451, 11449.656510304705, 11866.940665296619, 12140.939295303468, 12180.330081845583, 12190.57332993341, 12545.49818007306, 9468.384555003902, 10014.19886409071, 10446.380810974311, 10797.676069717922, 10866.61966831157, 9998.100170984646, 10104.898951010397, 10210.387376757419, 10376.377417354983, 10645.840312395476, 9082.909170908471, 11275.097992160443, 11287.661370158909, 11522.0, 11548.01841843419, 11369.090472762324, 11401.657950261173, 11460.0, 11591.478383472595, 11831.230969987268, 8983.371164018718, 10871.347719136877, 11028.779424411307, 11031.338119712844, 11201.887981120084], "value": 11139.824493549208, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1245.2528482910254, 1360.5034162530667, 1376.2224343246062, 1556.5330400879734, 1594.1232322222781, 10651.627193048245, 11234.382108984002, 11246.775064498715, 11259.774804503904, 11286.661400157998, 10629.202809789258, 10919.181061420406, 10944.124470042398, 10973.945130274358, 10994.835077473825, 2306.3657494189106, 2347.882605869708, 2349.823763217757, 2351.0478256306224, 2351.200591798788, 2256.4810093678466, 2340.0639744102323, 2356.858588484688, 2357.6817129687443, 2360.7403185649564, 2286.4398222435443, 2331.94170145746, 2376.489054853206, 2383.380321116505, 2389.0205015943466, 2301.551197516485, 2347.7182738071456, 2368.2658375903525, 2373.5608912351186, 2376.9286921392386, 1932.371979106782, 2326.8138548916045, 2338.0998315648426, 2338.8012018978407, 2339.9298021059394, 10824.567017319298, 10949.945250273777, 10951.014408703175, 10953.890461095367, 10976.670699878936, 4748.5014073522125, 5519.31008623922, 5678.6024978251535, 5685.40303268155, 5690.658560486384, 3286.2113092857544, 3368.4947257911263, 3493.580770307554, 3550.0592343029116, 3551.7691350062423, 1135.6138912769616, 1167.078008373384, 1176.014474024293, 1180.3744015671707, 1199.4722322178213, 15405.537833865003, 15474.61313467161, 15591.064536127755, 15596.688066238725, 15610.609734756608, 4295.484541854998, 4541.636669066466, 4590.334401511762, 4619.19164146273, 4624.1445332613575, 10270.024347686958, 10891.83662245067, 11513.539458421737, 11548.422578871023, 11580.305181689126, 5604.915926261109, 5607.943920560742, 5608.495235428831, 5612.326520817528, 5617.157426385993, 4458.621017213508, 5031.82388616404, 5073.8731531712, 5129.563987061067, 5141.5886729061585, 3701.222743223899, 3773.509443772292, 3792.8482860685262, 3817.7900215487857, 3833.4058220975794, 955.1976339874539, 958.108958668445, 973.4159504297506, 975.6487664440796, 977.1791694976254, 815.0830315894618, 820.5651004967377, 902.4720538484928, 918.5590916360051, 928.4800511713327, 6449.516286278548, 6537.542372034007, 6641.501887358468, 6688.398044176048, 6803.115594972622, 883.5758835758871, 1020.2705065877933, 1038.5689938675366, 1040.6461802987021, 1046.8693810684442, 4320.65434765218, 4397.890052748707, 4444.977775111104, 4488.735701083983, 4580.267157254823, 13102.934485327512, 13389.464421423228, 13559.322033898266, 13577.456901724017, 13635.795463068062, 10517.789644207072, 13480.191188528719, 13518.391672374766, 13530.594082177551, 13612.047156699135, 9825.459599722084, 10101.292909496411, 10105.44420056904, 10117.898821011842, 10124.645637402655, 6303.243610766737, 6345.303246120288, 6537.182852143482, 6563.474922006132, 6587.63767992765, 11220.86841934516, 11386.94306528462, 11521.423928803359, 11521.654350369503, 11551.768964620493, 11797.469113890067, 11798.0, 11825.70435739079, 11849.585264515701, 11882.58410955647, 8761.430507017023, 9068.773280668036, 9323.114304141096, 9325.0, 9331.16019558243, 10017.799644007226, 10106.39361638304, 10110.0, 10265.486725663837, 10293.74265643365, 8397.370197235354, 8631.654733810577, 8752.256058235122, 8814.427062240933, 8832.160944710242, 6985.196702379291, 8377.246047855722, 8544.786380340542, 8557.486550806723, 8596.269317108116, 7201.13586369639, 7232.674529646284, 7322.487425880137, 7365.558066516026, 7369.557826530426, 5756.769729210785, 5775.768969241351, 5899.351071382231, 5909.645421274738, 5926.436988486087, 5576.163575463551, 5613.915791263053, 5805.448482394166, 5966.642001479925, 5967.224260846062], "value": 5004.1555664647585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.8566716147122, 372.97762134271943, 375.2026942746663, 380.5319457067807, 387.6259409669668, 4118.9908472424295, 4270.252705776492, 4850.490698476658, 4883.095592718838, 4963.776630051647, 4464.330350447426, 4799.5680388765, 4827.672390092726, 4916.926246106311, 4930.654854160211, 3786.5834758176647, 4255.340422234543, 4274.850380236692, 4284.200635732984, 4350.521442641323, 3666.578343490493, 4305.827766889321, 4327.502337231212, 4356.411884395614, 4372.73763574185, 4182.289010868145, 4319.740815551061, 4340.739555626673, 4353.5211126776185, 4395.911941827639, 3646.4348026055873, 4248.362745588156, 4269.722468039598, 4308.138372325532, 4372.628326592244, 3531.3450578650973, 3633.4913112164054, 3799.392097264452, 4038.2125485530332, 4119.546849846514, 4004.379321205218, 4874.341963834872, 4894.7063176209185, 4898.7263311539255, 4965.528274813887, 4355.5208927018, 4390.604845563883, 5012.27322038306, 5041.252517378038, 5094.841198456943, 4523.954760452386, 5004.0, 5247.449017853147, 5375.11310633745, 5469.632591852037, 4145.274576949052, 4334.068175342317, 4582.747948862811, 4697.577218050392, 4708.964027913854, 4881.682690625098, 5535.418781027975, 5596.804111856066, 5649.017442702926, 5715.514181294637, 3613.1689711366535, 3782.470454136368, 4073.022474606073, 4119.814608342632, 4335.544767799335, 3931.8230679619487, 4267.637250833714, 4273.136998211144, 4538.72767633943, 4655.301704744248, 4614.884627884263, 4716.457607375127, 4742.312364707098, 4832.879178020507, 4867.515407800584, 5307.787688492494, 5318.787248510093, 5334.386545547234, 5351.866203344947, 5423.29497165366, 1496.7801241987745, 1538.8766200673572, 1675.7653928449784, 1693.1872701103293, 1710.8366310908452, 1346.8047133165637, 1368.117564171124, 1389.8811456777391, 1392.5335012770836, 1413.9363728632236, 4337.501187363494, 4609.501911878653, 4936.876578085505, 4973.0107956817565, 4988.226824842155, 4508.526604706459, 4625.421822272216, 5093.745312734349, 5107.438181799999, 5152.948470515322, 4594.517575654575, 4643.953560464352, 4892.804287828518, 4909.26361045846, 4940.654154209171, 2489.2283392148493, 2645.989048323288, 2682.409869828635, 2818.971810281912, 2825.7033011533495, 4835.355978967168, 5175.068487672255, 5339.786408543615, 5420.701861397661, 5593.468620481048, 4889.022195560902, 5241.034612020492, 5438.945610543923, 5582.553395728332, 5653.886922261532, 4846.878828029327, 5046.949530504721, 5583.35791383996, 5690.032694441955, 5721.342045664802, 4558.954410455985, 5340.252364669027, 5510.834674959758, 5559.860228653198, 5582.051051321204, 4596.816127355002, 4979.655493016793, 4981.252812078217, 4999.999999999913, 5309.787608495772, 5252.868678283074, 5641.617803638135, 5674.687892165971, 5677.460641239076, 5705.400932902152, 3864.85014713008, 4100.560166700809, 4104.2911678292185, 4337.3927650129635, 4355.477342718767, 4586.0, 4811.4707382188635, 5315.920261196008, 5378.0, 5450.236966824568, 4321.762303073361, 4563.361129441814, 4610.561996610317, 5248.0, 5348.491893270133, 4553.927962776995, 4881.463039065771, 4991.47589503097, 5147.3308469898675, 5284.0, 4547.248914406869, 4750.596199323097, 4877.3903262092235, 5177.0, 5180.430152683277, 4945.109880221595, 5279.656822306538, 5293.920591191212, 5300.761465734128, 5350.0, 4805.639577031807, 4986.254810816185, 5019.447860735389, 5054.570361519312, 5133.25469340426], "value": 4109.854703850308, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [626.896562067259, 723.9203687594363, 764.1975925278459, 772.7411317208735, 868.9652413903444, 15004.549863504099, 15790.210489475508, 15799.605009874758, 16016.59958501038, 16302.510924672279, 13891.583252502416, 14492.637684057878, 15057.0, 15261.6947661047, 15276.388944442237, 931.4876817750227, 958.2621381536213, 958.8082383523289, 958.9808203835954, 961.7980224152936, 939.2204470289678, 954.8949615542285, 966.3042609321277, 975.3903810118676, 976.2677991506371, 1008.7326858382535, 1009.1018993096151, 1009.9899001010006, 1010.206987514801, 1011.0344620887035, 1003.7440452684592, 1006.3358183598863, 1006.5420233793607, 1006.854006169105, 1009.399407352624, 746.0, 748.4985060009807, 748.8689479341093, 749.5465243527642, 749.7375918428561, 15683.843161568353, 16068.357265709357, 16095.839041609552, 16482.34070637173, 16717.331306747714, 11490.540378384854, 13626.318684065856, 13665.590032299047, 14022.298885055707, 14087.084339517898, 12297.56958506457, 13013.4305658078, 13189.47242110324, 13224.0, 13361.532346367927, 4522.135474978483, 4635.745034023127, 4653.906921861578, 4687.9531204687855, 4714.316424118484, 109609.54684343102, 114033.0, 114260.28609570864, 114401.0, 115476.0, 11461.598844040585, 11480.860270546591, 11636.418179091012, 11709.238899471677, 11744.882551174549, 24373.878130609235, 24408.26775196747, 24413.755862441503, 24416.511669766503, 24502.26493205207, 7695.6921723130445, 7773.650185741655, 7929.0, 7991.280784729286, 8168.42821002536, 4324.481062272484, 4402.273624851863, 4435.800388982503, 4447.132809102259, 4520.0, 3551.4140166872694, 3567.661072198137, 3650.1969566695284, 3656.6160553142067, 3662.5421822272215, 594.7234535940809, 630.0737915264554, 632.0708558419117, 633.7655067625009, 634.0837489827247, 682.4881338995754, 684.3532861445985, 692.5325405351367, 712.3304094151481, 730.1967835381039, 9194.035815080377, 9325.90674093264, 10149.797004060025, 10367.32612380193, 10772.569097236023, 677.6916502991226, 708.2952462300044, 710.8507213485123, 716.6309350684402, 729.7409419656052, 4784.497627749106, 4939.160342741742, 4968.801247950041, 4976.477469865685, 5024.221245706848, 82524.0, 99245.0, 99988.0, 101453.49273253586, 104650.47674761577, 48103.59481091957, 56013.0, 82163.5891820537, 87094.56452717696, 87278.56360718155, 74529.0, 75019.62490187732, 75898.0, 77221.61389193131, 77734.22265777383, 9041.954790226007, 10887.0, 10927.672169834917, 11052.0, 11140.944295278472, 49214.753926230136, 50022.749886250334, 50139.24791128063, 50881.491185087674, 51185.744071279405, 63761.36238637554, 63940.0, 64870.35129648832, 65147.0, 65521.0, 13168.80246796318, 13254.138480998705, 13661.795073073714, 13780.0, 13943.233122178377, 14222.857771422567, 14353.75234147724, 14578.85421145775, 14845.480408185662, 14890.702185956437, 13022.413991370222, 13445.596632101053, 13917.58247252584, 13997.860021399656, 14350.641233969234, 12658.493660253855, 13247.60900105475, 13809.309534523434, 13960.511382101984, 14005.579832605039, 12454.0, 13288.26914519672, 13301.46794128224, 13541.322933853466, 13868.511282991803, 9890.851637225303, 10108.747281317732, 10160.491975401348, 10477.842832357368, 10509.474526273503, 11178.161637877029, 11261.66215013551, 11292.43537823122, 11351.637803463636, 11361.147913906656], "value": 7753.457033073452, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1440.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 117.0, 115.0, 116.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 110.0, 146.0, 111.0, 111.0, 108.0, 104.0, 123.0, 130.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 184.0, 184.0, 182.0, 185.0, 186.0, 185.0, 186.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 96.0, 63.0, 61.0, 60.0, 75.0, 98.0, 59.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 87.0, 69.0, 69.0, 68.0, 83.0, 70.0, 69.0, 83.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 134.0, 88.0, 88.0, 92.0, 88.0, 90.0, 89.0, 89.0, 87.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 129.0, 114.0, 84.0, 86.0, 84.0, 85.0, 83.0, 84.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 59.0, 60.0, 66.0, 62.0, 63.0, 63.0, 64.0, 64.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 66.0, 65.0, 63.0, 61.0, 58.0, 58.0, 60.0, 57.0, 59.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 86.0, 81.0, 83.0, 84.0, 84.0, 86.0, 87.0, 121.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 138.0, 161.0, 124.0, 135.0, 132.0, 137.0, 161.0, 153.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 171.0, 170.0, 165.0, 164.0, 165.0, 165.0, 163.0, 149.0, 221.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 66.0, 70.0, 81.0, 82.0, 79.0, 80.0], "value": 72.0, "unit": "score"}]}]} 01:53:35 INFO - Return code: 0 01:53:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:53:35 ERROR - # TBPL SUCCESS # 01:53:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:53:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:53:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:53:35 INFO - 2017-05-09 01:53:35.730 screenresolution[2623:39432] starting screenresolution argv=screenresolution get 01:53:35 INFO - 2017-05-09 01:53:35.732 screenresolution[2623:39432] Display 0: 1600x1200x32@60 01:53:35 INFO - 2017-05-09 01:53:35.739 screenresolution[2624:39435] starting screenresolution argv=screenresolution list 01:53:35 INFO - Available Modes on Display 0 01:53:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:53:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:53:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:53:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:53:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:53:35 INFO - Intel Iris: 01:53:35 INFO - Chipset Model: Intel Iris 01:53:35 INFO - Type: GPU 01:53:35 INFO - Bus: Built-In 01:53:35 INFO - VRAM (Dynamic, Max): 1536 MB 01:53:35 INFO - Vendor: Intel (0x8086) 01:53:35 INFO - Device ID: 0x0a2e 01:53:35 INFO - Revision ID: 0x0009 01:53:35 INFO - Displays: 01:53:35 INFO - Display: 01:53:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:53:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:53:35 INFO - Display Serial Number: 1600x1200 60 01:53:35 INFO - Main Display: Yes 01:53:35 INFO - Mirror: Off 01:53:35 INFO - Online: Yes 01:53:35 INFO - Rotation: Supported 01:53:35 INFO - Return code: 0 01:53:35 INFO - Running post-action listener: _resource_record_post_action 01:53:35 INFO - [mozharness: 2017-05-09 08:53:35.982945Z] Finished run-tests step (success) 01:53:35 INFO - Running post-run listener: _resource_record_post_run 01:53:36 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 12846080; Write bytes: 520969216; Read time: 179; Write time: 1830 01:53:36 INFO - TinderboxPrint: CPU usage
53.9% 01:53:36 INFO - TinderboxPrint: I/O read bytes / time
12,846,080 / 179 01:53:36 INFO - TinderboxPrint: I/O write bytes / time
520,969,216 / 1,830 01:53:36 INFO - TinderboxPrint: CPU idle
2,217.3 (46.1%) 01:53:36 INFO - TinderboxPrint: CPU system
958.9 (20.0%) 01:53:36 INFO - TinderboxPrint: CPU user
1,629.5 (33.9%) 01:53:36 INFO - TinderboxPrint: Swap in / out
213,929,984 / 0 01:53:36 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 199959040; Write bytes: 202557440; Read time: 15455; Write time: 435 01:53:36 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 12846080; Write bytes: 308487168; Read time: 179; Write time: 1366 01:53:36 INFO - Running post-run listener: _upload_blobber_files 01:53:36 INFO - Blob upload gear active. 01:53:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:53:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:53:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:53:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:53:36 INFO - (blobuploader) - INFO - Open directory for files ... 01:53:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:53:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:53:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:53:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:53:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:53:37 INFO - (blobuploader) - INFO - Done attempting. 01:53:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:53:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:53:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:53:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:53:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:53:38 INFO - (blobuploader) - INFO - Done attempting. 01:53:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:53:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:53:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:53:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:53:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:53:39 INFO - (blobuploader) - INFO - Done attempting. 01:53:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:53:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:53:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:53:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:53:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:53:41 INFO - (blobuploader) - INFO - Done attempting. 01:53:41 INFO - (blobuploader) - INFO - Iteration through files over. 01:53:41 INFO - Return code: 0 01:53:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:53:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:53:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13c29437f8b9fe31d0828e23e67b1c2c7442df3e5d466cee72b7f4336fc68ab5bd2a860056dc95b4bdfbbbc680c780e59167653a51da7791200ce611df56f428", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74b2dc1e9b965dd1a1e08769e45816598a5db10e2c2b02ea906fcc1dc20eaf294d1bbb2f1d98405a002a01a0c345ef13b3870231e8cca44988614f47e222f996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7c53c31f0736c9bdda87eaf1c627037f934f4fa7f08b4badafedee60bfa2953674e578cc7d85048f3ac03bc7b3bd36e2d9111179286a459a24a39ac13bea07d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1edc9005909b351bb2170ba52e515f62f1d9fb7f03c88affbfa2a6f4da6d8a4c055f3f287e6167e1db140c396353c7049fd5365f24050ae774c667c1cdba3c39"} 01:53:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:53:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:53:41 INFO - Contents: 01:53:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13c29437f8b9fe31d0828e23e67b1c2c7442df3e5d466cee72b7f4336fc68ab5bd2a860056dc95b4bdfbbbc680c780e59167653a51da7791200ce611df56f428", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74b2dc1e9b965dd1a1e08769e45816598a5db10e2c2b02ea906fcc1dc20eaf294d1bbb2f1d98405a002a01a0c345ef13b3870231e8cca44988614f47e222f996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7c53c31f0736c9bdda87eaf1c627037f934f4fa7f08b4badafedee60bfa2953674e578cc7d85048f3ac03bc7b3bd36e2d9111179286a459a24a39ac13bea07d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1edc9005909b351bb2170ba52e515f62f1d9fb7f03c88affbfa2a6f4da6d8a4c055f3f287e6167e1db140c396353c7049fd5365f24050ae774c667c1cdba3c39"} 01:53:41 INFO - Running post-run listener: copy_logs_to_upload_dir 01:53:41 INFO - Copying logs to upload dir... 01:53:41 INFO - mkdir: /builds/slave/test/build/upload/logs 01:53:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.702318 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-09 01:53:41.717548) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 01:53:41.721177) ========= 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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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/13c29437f8b9fe31d0828e23e67b1c2c7442df3e5d466cee72b7f4336fc68ab5bd2a860056dc95b4bdfbbbc680c780e59167653a51da7791200ce611df56f428", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74b2dc1e9b965dd1a1e08769e45816598a5db10e2c2b02ea906fcc1dc20eaf294d1bbb2f1d98405a002a01a0c345ef13b3870231e8cca44988614f47e222f996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7c53c31f0736c9bdda87eaf1c627037f934f4fa7f08b4badafedee60bfa2953674e578cc7d85048f3ac03bc7b3bd36e2d9111179286a459a24a39ac13bea07d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1edc9005909b351bb2170ba52e515f62f1d9fb7f03c88affbfa2a6f4da6d8a4c055f3f287e6167e1db140c396353c7049fd5365f24050ae774c667c1cdba3c39"} build_url:https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/WtHAWfcTTOeeiIkQQWWmow/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/13c29437f8b9fe31d0828e23e67b1c2c7442df3e5d466cee72b7f4336fc68ab5bd2a860056dc95b4bdfbbbc680c780e59167653a51da7791200ce611df56f428", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/74b2dc1e9b965dd1a1e08769e45816598a5db10e2c2b02ea906fcc1dc20eaf294d1bbb2f1d98405a002a01a0c345ef13b3870231e8cca44988614f47e222f996", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7c53c31f0736c9bdda87eaf1c627037f934f4fa7f08b4badafedee60bfa2953674e578cc7d85048f3ac03bc7b3bd36e2d9111179286a459a24a39ac13bea07d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1edc9005909b351bb2170ba52e515f62f1d9fb7f03c88affbfa2a6f4da6d8a4c055f3f287e6167e1db140c396353c7049fd5365f24050ae774c667c1cdba3c39"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 01:53:41.756756) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 01:53:41.757095) ========= 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.aI9IH9AAzb/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.9YmfvOuJ0i/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005070 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 01:53:41.805680) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 01:53:41.806071) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 01:53:41.806401) ========= ========= Total master_lag: 0.20 =========