builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1485952178.29 results: success (0) buildid: 20170201030207 builduid: 92cce3f3884d4535b70ba16b3a83a5df revision: 1d025ac534a6333a8170a59a95a8a3673d4028ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:38.290882) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:38.291304) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:38.291578) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:38.331613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-01 04:29:38.331905) ========= 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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-01 04:29:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.6M=0.001s 2017-02-01 04:29:38 (12.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.619515 ========= master_lag: 1.65 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-01 04:29:40.606259) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:40.606537) ========= 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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.019823 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:40.672235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:40.672541) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1d025ac534a6333a8170a59a95a8a3673d4028ee --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1d025ac534a6333a8170a59a95a8a3673d4028ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Erl7FcPxcP/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.DRQlQHU0YN/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-01 04:29:40,764 truncating revision to first 12 chars 2017-02-01 04:29:40,764 Setting DEBUG logging. 2017-02-01 04:29:40,764 attempt 1/10 2017-02-01 04:29:40,764 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1d025ac534a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-01 04:29:41,432 unpacking tar archive at: mozilla-central-1d025ac534a6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.909559 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:41.612684) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:41.613061) ========= script_repo_revision: 1d025ac534a6333a8170a59a95a8a3673d4028ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:41.613469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:41.613750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 04:29:41.642808) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-01 04:29:41.643209) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Erl7FcPxcP/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.DRQlQHU0YN/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:29:41 INFO - MultiFileLogger online at 20170201 04:29:41 in /builds/slave/test 04:29:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:29:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:29:41 INFO - {'append_to_log': False, 04:29:41 INFO - 'base_work_dir': '/builds/slave/test', 04:29:41 INFO - 'blob_upload_branch': 'Firefox', 04:29:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:29:41 INFO - 'branch': 'Firefox', 04:29:41 INFO - 'buildbot_json_path': 'buildprops.json', 04:29:41 INFO - 'config_files': ('talos/mac_config.py',), 04:29:41 INFO - 'default_actions': ('clobber', 04:29:41 INFO - 'read-buildbot-config', 04:29:41 INFO - 'download-and-extract', 04:29:41 INFO - 'populate-webroot', 04:29:41 INFO - 'create-virtualenv', 04:29:41 INFO - 'install', 04:29:41 INFO - 'run-tests'), 04:29:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:29:41 INFO - 'download_minidump_stackwalk': True, 04:29:41 INFO - 'download_symbols': 'ondemand', 04:29:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:29:41 INFO - 'tooltool.py': '/tools/tooltool.py', 04:29:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:29:41 INFO - '/tools/misc-python/virtualenv.py')}, 04:29:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:29:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:29:41 INFO - 'installer_path': 'installer.exe', 04:29:41 INFO - 'log_level': 'info', 04:29:41 INFO - 'log_name': 'talos', 04:29:41 INFO - 'log_to_console': True, 04:29:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:29:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:29:41 INFO - 'opt_config_files': (), 04:29:41 INFO - 'pip_index': False, 04:29:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:41 INFO - 'cmd': ('bash', 04:29:41 INFO - '-c', 04:29:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:41 INFO - 'enabled': True, 04:29:41 INFO - 'halt_on_failure': False, 04:29:41 INFO - 'name': 'check_screen_resolution'},), 04:29:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:41 INFO - 'cmd': ('bash', 04:29:41 INFO - '-c', 04:29:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:41 INFO - 'enabled': True, 04:29:41 INFO - 'halt_on_failure': False, 04:29:41 INFO - 'name': 'check_screen_resolution'},), 04:29:41 INFO - 'run_cmd_checks_enabled': True, 04:29:41 INFO - 'sps_profile': False, 04:29:41 INFO - 'sps_profile_interval': 0, 04:29:41 INFO - 'suite': 'dromaeojs', 04:29:41 INFO - 'system_bits': '32', 04:29:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:29:41 INFO - 'title': 't-yosemite-r7-0127', 04:29:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:29:41 INFO - 'use_talos_json': True, 04:29:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:29:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:29:41 INFO - 'work_dir': 'build'} 04:29:41 INFO - [mozharness: 2017-02-01 12:29:41.858563Z] Running clobber step. 04:29:41 INFO - Running pre-action listener: _resource_record_pre_action 04:29:41 INFO - Running main action method: clobber 04:29:41 INFO - rmtree: /builds/slave/test/build 04:29:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:43 INFO - Running post-action listener: _resource_record_post_action 04:29:43 INFO - [mozharness: 2017-02-01 12:29:43.643784Z] Finished clobber step (success) 04:29:43 INFO - [mozharness: 2017-02-01 12:29:43.643975Z] Running read-buildbot-config step. 04:29:43 INFO - Running pre-action listener: _resource_record_pre_action 04:29:43 INFO - Running main action method: read_buildbot_config 04:29:43 INFO - Using buildbot properties: 04:29:43 INFO - { 04:29:43 INFO - "project": "", 04:29:43 INFO - "product": "firefox", 04:29:43 INFO - "script_repo_revision": "production", 04:29:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:29:43 INFO - "repository": "", 04:29:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:29:43 INFO - "buildid": "20170201030207", 04:29:43 INFO - "stage_platform": "macosx64", 04:29:43 INFO - "basedir": "/builds/slave/test", 04:29:43 INFO - "nightly_build": "True", 04:29:43 INFO - "buildnumber": 58, 04:29:43 INFO - "slavename": "t-yosemite-r7-0127", 04:29:43 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:29:43 INFO - "platform": "yosemite_r7", 04:29:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:29:43 INFO - "branch": "mozilla-central", 04:29:43 INFO - "repo_path": "mozilla-central", 04:29:43 INFO - "revision": "1d025ac534a6333a8170a59a95a8a3673d4028ee", 04:29:43 INFO - "pgo_build": "False", 04:29:43 INFO - "builduid": "92cce3f3884d4535b70ba16b3a83a5df", 04:29:43 INFO - "slavebuilddir": "test" 04:29:43 INFO - } 04:29:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:29:43 INFO - Running post-action listener: _resource_record_post_action 04:29:43 INFO - [mozharness: 2017-02-01 12:29:43.648244Z] Finished read-buildbot-config step (success) 04:29:43 INFO - [mozharness: 2017-02-01 12:29:43.648395Z] Running download-and-extract step. 04:29:43 INFO - Running pre-action listener: _resource_record_pre_action 04:29:43 INFO - Running main action method: download_and_extract 04:29:43 INFO - mkdir: /builds/slave/test/build/tests 04:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:29:43 INFO - trying https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:29:43 INFO - Downloading https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 04:29:45 INFO - Downloaded 1199 bytes. 04:29:45 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:29:45 INFO - Using the following test package requirements: 04:29:45 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:29:45 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:29:45 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:29:45 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'jsshell-mac.zip'], 04:29:45 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:29:45 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:29:45 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:29:45 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:29:45 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:29:45 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:45 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:29:45 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:29:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:29:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:29:45 INFO - Fetch https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:29:45 INFO - Expected file size: 16372269 04:29:45 INFO - Obtained file size: 16372269 04:29:52 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:29:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:29:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:29:52 INFO - Fetch https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:29:53 INFO - Expected file size: 16372269 04:29:53 INFO - Obtained file size: 16372269 04:29:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:29:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:29:59 INFO - Fetch https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:30:01 INFO - Expected file size: 14403454 04:30:01 INFO - Obtained file size: 14403454 04:30:01 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')]} 04:30:01 INFO - trying https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:30:01 INFO - Downloading https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 04:30:03 INFO - Downloaded 70276782 bytes. 04:30:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:30:03 INFO - mkdir: /builds/slave/test/properties 04:30:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:30:03 INFO - Writing to file /builds/slave/test/properties/build_url 04:30:03 INFO - Contents: 04:30:03 INFO - build_url:https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:30:04 INFO - Running post-action listener: _resource_record_post_action 04:30:04 INFO - Running post-action listener: set_extra_try_arguments 04:30:04 INFO - [mozharness: 2017-02-01 12:30:04.119930Z] Finished download-and-extract step (success) 04:30:04 INFO - [mozharness: 2017-02-01 12:30:04.120031Z] Running populate-webroot step. 04:30:04 INFO - Running pre-action listener: _resource_record_pre_action 04:30:04 INFO - Running main action method: populate_webroot 04:30:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:30:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:30:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:30:04 INFO - '--tppagecycles', 04:30:04 INFO - '7'], 04:30:04 INFO - 'tests': ['tsvgm']}}, 04:30:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:30:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:30:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:30:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:30:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:30:04 INFO - 'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:30:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:30:04 INFO - 'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['damp', 'tps']}, 04:30:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:30:04 INFO - 'tests': ['damp', 'tps']}, 04:30:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['dromaeo_dom']}, 04:30:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:30:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['basic_compositor_video']}, 04:30:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:30:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['a11yr', 04:30:04 INFO - 'ts_paint', 04:30:04 INFO - 'tpaint', 04:30:04 INFO - 'sessionrestore', 04:30:04 INFO - 'sessionrestore_no_auto_restore', 04:30:04 INFO - 'tabpaint']}, 04:30:04 INFO - 'other-e10s': {'tests': ['a11yr', 04:30:04 INFO - 'ts_paint', 04:30:04 INFO - 'tpaint', 04:30:04 INFO - 'sessionrestore', 04:30:04 INFO - 'sessionrestore_no_auto_restore', 04:30:04 INFO - 'tabpaint']}, 04:30:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:30:04 INFO - 'ts_paint', 04:30:04 INFO - 'tpaint', 04:30:04 INFO - 'sessionrestore', 04:30:04 INFO - 'sessionrestore_no_auto_restore', 04:30:04 INFO - 'tabpaint']}, 04:30:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:30:04 INFO - 'ts_paint', 04:30:04 INFO - 'tpaint', 04:30:04 INFO - 'sessionrestore', 04:30:04 INFO - 'sessionrestore_no_auto_restore', 04:30:04 INFO - 'tabpaint']}, 04:30:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['a11yr', 04:30:04 INFO - 'ts_paint', 04:30:04 INFO - 'tpaint', 04:30:04 INFO - 'sessionrestore', 04:30:04 INFO - 'sessionrestore_no_auto_restore', 04:30:04 INFO - 'tabpaint']}, 04:30:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['a11yr', 04:30:04 INFO - 'ts_paint', 04:30:04 INFO - 'tpaint', 04:30:04 INFO - 'sessionrestore', 04:30:04 INFO - 'sessionrestore_no_auto_restore', 04:30:04 INFO - 'tabpaint']}, 04:30:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['tsvgx', 04:30:04 INFO - 'tsvgr_opacity', 04:30:04 INFO - 'tart', 04:30:04 INFO - 'tscrollx', 04:30:04 INFO - 'cart', 04:30:04 INFO - 'tsvg_static']}, 04:30:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:30:04 INFO - 'tsvgr_opacity', 04:30:04 INFO - 'tart', 04:30:04 INFO - 'tscrollx', 04:30:04 INFO - 'cart', 04:30:04 INFO - 'tsvg_static']}, 04:30:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:30:04 INFO - 'talos_options': ['--disable-e10s'], 04:30:04 INFO - 'tests': ['tp5o']}, 04:30:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:30:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:30:04 INFO - 'talos_options': ['--disable-e10s', 04:30:04 INFO - '--xperf_path', 04:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:04 INFO - 'tests': ['tp5n']}, 04:30:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:30:04 INFO - 'talos_options': ['--xperf_path', 04:30:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:04 INFO - 'tests': ['tp5n']}}, 04:30:04 INFO - 'talos.zip': {'path': '', 04:30:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:30:04 INFO - Running post-action listener: _resource_record_post_action 04:30:04 INFO - [mozharness: 2017-02-01 12:30:04.139185Z] Finished populate-webroot step (success) 04:30:04 INFO - [mozharness: 2017-02-01 12:30:04.139329Z] Running create-virtualenv step. 04:30:04 INFO - Running pre-action listener: _resource_record_pre_action 04:30:04 INFO - Running main action method: create_virtualenv 04:30:04 INFO - Creating virtualenv /builds/slave/test/build/venv 04:30:04 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 04:30:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:30:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:30:04 INFO - Using real prefix '/tools/python27' 04:30:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:30:04 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:07 INFO - Installing pip.................done. 04:30:07 INFO - Return code: 0 04:30:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:30:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:30:08 INFO - Reading from file tmpfile_stdout 04:30:08 INFO - Output received: 04:30:08 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) 04:30:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:08 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')]} 04:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 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')]} 04:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30:08 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 04:30:08 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 04:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:08 INFO - 'HOME': '/Users/cltbld', 04:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:08 INFO - 'LOGNAME': 'cltbld', 04:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:08 INFO - 'MOZ_NO_REMOTE': '1', 04:30:08 INFO - 'NO_EM_RESTART': '1', 04:30:08 INFO - 'PAGER': '/bin/cat', 04:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:08 INFO - 'PWD': '/builds/slave/test', 04:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:08 INFO - 'SHELL': '/bin/bash', 04:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:08 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:08 INFO - 'USER': 'cltbld', 04:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:08 INFO - 'XPC_FLAGS': '0x0', 04:30:08 INFO - 'XPC_SERVICE_NAME': '0', 04:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:08 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 04:30:08 INFO - Cleaning up... 04:30:08 INFO - Return code: 0 04:30:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:30:08 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')]} 04:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 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')]} 04:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:08 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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30:08 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 04:30:08 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 04:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:08 INFO - 'HOME': '/Users/cltbld', 04:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:08 INFO - 'LOGNAME': 'cltbld', 04:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:08 INFO - 'MOZ_NO_REMOTE': '1', 04:30:08 INFO - 'NO_EM_RESTART': '1', 04:30:08 INFO - 'PAGER': '/bin/cat', 04:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:08 INFO - 'PWD': '/builds/slave/test', 04:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:08 INFO - 'SHELL': '/bin/bash', 04:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:08 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:08 INFO - 'USER': 'cltbld', 04:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:08 INFO - 'XPC_FLAGS': '0x0', 04:30:08 INFO - 'XPC_SERVICE_NAME': '0', 04:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:08 INFO - Downloading/unpacking psutil>=3.1.1 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:08 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 04:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:12 INFO - Installing collected packages: psutil 04:30:12 INFO - Running setup.py install for psutil 04:30:12 INFO - building 'psutil._psutil_osx' extension 04:30:12 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 04:30:12 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 04:30:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:30:13 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 04:30:13 INFO - building 'psutil._psutil_posix' extension 04:30:13 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 04:30:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:30:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:30:13 INFO - ^ 04:30:13 INFO - 1 warning generated. 04:30:13 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 04:30:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:13 INFO - Successfully installed psutil 04:30:13 INFO - Cleaning up... 04:30:13 INFO - Return code: 0 04:30:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:30:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:13 INFO - 'HOME': '/Users/cltbld', 04:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:13 INFO - 'LOGNAME': 'cltbld', 04:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:13 INFO - 'MOZ_NO_REMOTE': '1', 04:30:13 INFO - 'NO_EM_RESTART': '1', 04:30:13 INFO - 'PAGER': '/bin/cat', 04:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:13 INFO - 'PWD': '/builds/slave/test', 04:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:13 INFO - 'SHELL': '/bin/bash', 04:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:13 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:13 INFO - 'USER': 'cltbld', 04:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:13 INFO - 'XPC_FLAGS': '0x0', 04:30:13 INFO - 'XPC_SERVICE_NAME': '0', 04:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:30:13 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 04:30:13 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 04:30:13 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 04:30:13 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 04:30:13 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 04:30:13 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 04:30:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:30:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:30:16 INFO - Installing collected packages: mozsystemmonitor 04:30:16 INFO - Running setup.py install for mozsystemmonitor 04:30:16 INFO - Successfully installed mozsystemmonitor 04:30:16 INFO - Cleaning up... 04:30:16 INFO - Return code: 0 04:30:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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', '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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:30: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 jsonschema==2.5.1 04:30:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:16 INFO - 'HOME': '/Users/cltbld', 04:30:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:16 INFO - 'LOGNAME': 'cltbld', 04:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:16 INFO - 'MOZ_NO_REMOTE': '1', 04:30:16 INFO - 'NO_EM_RESTART': '1', 04:30:16 INFO - 'PAGER': '/bin/cat', 04:30:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:16 INFO - 'PWD': '/builds/slave/test', 04:30:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:16 INFO - 'SHELL': '/bin/bash', 04:30:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:16 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:16 INFO - 'USER': 'cltbld', 04:30:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:16 INFO - 'XPC_FLAGS': '0x0', 04:30:16 INFO - 'XPC_SERVICE_NAME': '0', 04:30:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:16 INFO - Downloading/unpacking jsonschema==2.5.1 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:19 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:20 INFO - warning: no files found matching '*.txt' 04:30:20 INFO - no previously-included directories found matching 'build' 04:30:20 INFO - no previously-included directories found matching 'dist' 04:30:20 INFO - no previously-included directories found matching '.git*' 04:30:20 INFO - Installing collected packages: jsonschema, functools32 04:30:20 INFO - Running setup.py install for functools32 04:30:20 INFO - warning: no files found matching '*.txt' 04:30:20 INFO - no previously-included directories found matching 'build' 04:30:20 INFO - no previously-included directories found matching 'dist' 04:30:20 INFO - no previously-included directories found matching '.git*' 04:30:20 INFO - Successfully installed jsonschema functools32 04:30:20 INFO - Cleaning up... 04:30:20 INFO - Return code: 0 04:30:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30: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 04:30: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 04:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:20 INFO - 'HOME': '/Users/cltbld', 04:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:20 INFO - 'LOGNAME': 'cltbld', 04:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:20 INFO - 'MOZ_NO_REMOTE': '1', 04:30:20 INFO - 'NO_EM_RESTART': '1', 04:30:20 INFO - 'PAGER': '/bin/cat', 04:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:20 INFO - 'PWD': '/builds/slave/test', 04:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:20 INFO - 'SHELL': '/bin/bash', 04:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:20 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:20 INFO - 'USER': 'cltbld', 04:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:20 INFO - 'XPC_FLAGS': '0x0', 04:30:20 INFO - 'XPC_SERVICE_NAME': '0', 04:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:30:20 INFO - Cleaning up... 04:30:20 INFO - Return code: 0 04:30:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30: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 04:30: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 04:30:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:20 INFO - 'HOME': '/Users/cltbld', 04:30:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:20 INFO - 'LOGNAME': 'cltbld', 04:30:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:20 INFO - 'MOZ_NO_REMOTE': '1', 04:30:20 INFO - 'NO_EM_RESTART': '1', 04:30:20 INFO - 'PAGER': '/bin/cat', 04:30:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:20 INFO - 'PWD': '/builds/slave/test', 04:30:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:20 INFO - 'SHELL': '/bin/bash', 04:30:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:20 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:20 INFO - 'USER': 'cltbld', 04:30:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:20 INFO - 'XPC_FLAGS': '0x0', 04:30:20 INFO - 'XPC_SERVICE_NAME': '0', 04:30:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:20 INFO - Downloading/unpacking blobuploader==1.2.4 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:23 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:24 INFO - Downloading docopt-0.6.1.tar.gz 04:30:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:24 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:24 INFO - Running setup.py install for blobuploader 04:30:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:24 INFO - Running setup.py install for requests 04:30:25 INFO - Running setup.py install for docopt 04:30:25 INFO - Successfully installed blobuploader requests docopt 04:30:25 INFO - Cleaning up... 04:30:25 INFO - Return code: 0 04:30:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:25 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')]} 04:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:25 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')]} 04:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:25 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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30:25 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 04:30:25 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 04:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:25 INFO - 'HOME': '/Users/cltbld', 04:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:25 INFO - 'LOGNAME': 'cltbld', 04:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:25 INFO - 'MOZ_NO_REMOTE': '1', 04:30:25 INFO - 'NO_EM_RESTART': '1', 04:30:25 INFO - 'PAGER': '/bin/cat', 04:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:25 INFO - 'PWD': '/builds/slave/test', 04:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:25 INFO - 'SHELL': '/bin/bash', 04:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:25 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:25 INFO - 'USER': 'cltbld', 04:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:25 INFO - 'XPC_FLAGS': '0x0', 04:30:25 INFO - 'XPC_SERVICE_NAME': '0', 04:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:25 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HwaSSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5hf5Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-w8AV6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-PqXQwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-1VSnxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-LR_spL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-MSdsEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZMZgfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-iY8LKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-j_hMiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:26 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-LlWsHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5xatSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-aXFJSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-eSXzAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-u0PUQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-LbZM0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:27 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-9rw8z7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:27 INFO - Running setup.py install for manifestparser 04:30:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:27 INFO - Running setup.py install for mozcrash 04:30:27 INFO - Running setup.py install for mozdebug 04:30:28 INFO - Running setup.py install for mozdevice 04:30:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Running setup.py install for mozfile 04:30:28 INFO - Running setup.py install for mozhttpd 04:30:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Running setup.py install for mozinfo 04:30:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Running setup.py install for mozInstall 04:30:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:30:28 INFO - Running setup.py install for mozleak 04:30:28 INFO - Running setup.py install for mozlog 04:30:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:30:29 INFO - Running setup.py install for moznetwork 04:30:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:30:29 INFO - Running setup.py install for mozprocess 04:30:29 INFO - Running setup.py install for mozprofile 04:30:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:30:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:30:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:30:29 INFO - Running setup.py install for mozrunner 04:30:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:30:29 INFO - Running setup.py install for mozscreenshot 04:30:29 INFO - Running setup.py install for moztest 04:30:30 INFO - Running setup.py install for mozversion 04:30:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:30:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:30:30 INFO - Cleaning up... 04:30:30 INFO - Return code: 0 04:30:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:30 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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30:30 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 04:30:30 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 04:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:30 INFO - 'HOME': '/Users/cltbld', 04:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:30 INFO - 'LOGNAME': 'cltbld', 04:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:30 INFO - 'MOZ_NO_REMOTE': '1', 04:30:30 INFO - 'NO_EM_RESTART': '1', 04:30:30 INFO - 'PAGER': '/bin/cat', 04:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:30 INFO - 'PWD': '/builds/slave/test', 04:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:30 INFO - 'SHELL': '/bin/bash', 04:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:30 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:30 INFO - 'USER': 'cltbld', 04:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:30 INFO - 'XPC_FLAGS': '0x0', 04:30:30 INFO - 'XPC_SERVICE_NAME': '0', 04:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:30 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-JHULY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:30 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)) 04:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:30 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-npRUZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:30 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)) 04:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:30 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vd6UYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:30 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)) 04:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:30 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-8xqLil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:30 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)) 04:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:30 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-o7HglB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HSMX4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-qBPvqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-LQmXwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-tzvNsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-53ch52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-C_LnB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-fEgEX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-9JYWRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:31 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)) 04:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:31 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-FrUOS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:32 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)) 04:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:32 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-WQ5pTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:32 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)) 04:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:32 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZnGWsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:32 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)) 04:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:32 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-JEjFJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:32 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)) 04:30:32 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)) 04:30:32 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)) 04:30:32 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)) 04:30:32 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)) 04:30:32 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)) 04:30:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:30:32 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 04:30:32 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 04:30:32 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 04:30:32 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 04:30:32 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 04:30:32 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 04:30:34 INFO - Downloading blessings-1.6.tar.gz 04:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:30:35 INFO - Installing collected packages: blessings 04:30:35 INFO - Running setup.py install for blessings 04:30:35 INFO - Successfully installed blessings 04:30:35 INFO - Cleaning up... 04:30:35 INFO - Return code: 0 04:30:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:30:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:35 INFO - Reading from file tmpfile_stdout 04:30:35 INFO - Current package versions: 04:30:35 INFO - blessings == 1.6 04:30:35 INFO - blobuploader == 1.2.4 04:30:35 INFO - docopt == 0.6.1 04:30:35 INFO - functools32 == 3.2.3-2 04:30:35 INFO - jsonschema == 2.5.1 04:30:35 INFO - manifestparser == 1.1 04:30:35 INFO - mozInstall == 1.12 04:30:35 INFO - mozcrash == 1.0 04:30:35 INFO - mozdebug == 0.1 04:30:35 INFO - mozdevice == 0.49 04:30:35 INFO - mozfile == 1.2 04:30:35 INFO - mozhttpd == 0.7 04:30:35 INFO - mozinfo == 0.9 04:30:35 INFO - mozleak == 0.1 04:30:35 INFO - mozlog == 3.4 04:30:35 INFO - moznetwork == 0.27 04:30:35 INFO - mozprocess == 0.24 04:30:35 INFO - mozprofile == 0.28 04:30:35 INFO - mozrunner == 6.13 04:30:35 INFO - mozscreenshot == 0.1 04:30:35 INFO - mozsystemmonitor == 0.3 04:30:35 INFO - moztest == 0.8 04:30:35 INFO - mozversion == 1.4 04:30:35 INFO - psutil == 3.1.1 04:30:35 INFO - requests == 1.2.3 04:30:35 INFO - wsgiref == 0.1.2 04:30:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:35 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')]} 04:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:35 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')]} 04:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:35 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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30:35 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 04:30:35 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 04:30:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:35 INFO - 'HOME': '/Users/cltbld', 04:30:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:35 INFO - 'LOGNAME': 'cltbld', 04:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:35 INFO - 'MOZ_NO_REMOTE': '1', 04:30:35 INFO - 'NO_EM_RESTART': '1', 04:30:35 INFO - 'PAGER': '/bin/cat', 04:30:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:35 INFO - 'PWD': '/builds/slave/test', 04:30:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:35 INFO - 'SHELL': '/bin/bash', 04:30:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:35 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:35 INFO - 'USER': 'cltbld', 04:30:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:35 INFO - 'XPC_FLAGS': '0x0', 04:30:35 INFO - 'XPC_SERVICE_NAME': '0', 04:30:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 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)) 04:30:35 INFO - Cleaning up... 04:30:35 INFO - Return code: 0 04:30:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:30:35 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')]} 04:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:35 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')]} 04:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:35 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 0x101ca2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ca5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbf1d59270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, '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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 04:30:35 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 04:30:35 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 04:30:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:35 INFO - 'HOME': '/Users/cltbld', 04:30:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:35 INFO - 'LOGNAME': 'cltbld', 04:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:35 INFO - 'MOZ_NO_REMOTE': '1', 04:30:35 INFO - 'NO_EM_RESTART': '1', 04:30:35 INFO - 'PAGER': '/bin/cat', 04:30:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:35 INFO - 'PWD': '/builds/slave/test', 04:30:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:35 INFO - 'SHELL': '/bin/bash', 04:30:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:35 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:35 INFO - 'USER': 'cltbld', 04:30:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:35 INFO - 'XPC_FLAGS': '0x0', 04:30:35 INFO - 'XPC_SERVICE_NAME': '0', 04:30:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:30:36 INFO - Cleaning up... 04:30:36 INFO - Return code: 0 04:30:36 INFO - Running post-action listener: _resource_record_post_action 04:30:36 INFO - Running post-action listener: _start_resource_monitoring 04:30:36 INFO - Starting resource monitoring. 04:30:36 INFO - [mozharness: 2017-02-01 12:30:36.190803Z] Finished create-virtualenv step (success) 04:30:36 INFO - [mozharness: 2017-02-01 12:30:36.191772Z] Running install step. 04:30:36 INFO - Running pre-action listener: _resource_record_pre_action 04:30:36 INFO - Running main action method: install 04:30:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:36 INFO - Reading from file tmpfile_stdout 04:30:36 INFO - Detecting whether we're running mozinstall >=1.0... 04:30:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:30:36 INFO - Reading from file tmpfile_stdout 04:30:36 INFO - Output received: 04:30:36 INFO - Usage: mozinstall [options] installer 04:30:36 INFO - Options: 04:30:36 INFO - -h, --help show this help message and exit 04:30:36 INFO - -d DEST, --destination=DEST 04:30:36 INFO - Directory to install application into. [default: 04:30:36 INFO - "/builds/slave/test"] 04:30:36 INFO - --app=APP Application being installed. [default: firefox] 04:30:36 INFO - mkdir: /builds/slave/test/build/application 04:30:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:30:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:30:58 INFO - Reading from file tmpfile_stdout 04:30:58 INFO - Output received: 04:30:58 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:30:58 INFO - Running post-action listener: _resource_record_post_action 04:30:58 INFO - [mozharness: 2017-02-01 12:30:58.276667Z] Finished install step (success) 04:30:58 INFO - [mozharness: 2017-02-01 12:30:58.276862Z] Running run-tests step. 04:30:58 INFO - Running pre-action listener: _resource_record_pre_action 04:30:58 INFO - Running main action method: run_tests 04:30:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:58 INFO - 2017-02-01 04:30:58.291 screenresolution[1904:15188] starting screenresolution argv=screenresolution get 04:30:58 INFO - 2017-02-01 04:30:58.294 screenresolution[1904:15188] Display 0: 1600x1200x32@60 04:30:58 INFO - 2017-02-01 04:30:58.300 screenresolution[1905:15190] starting screenresolution argv=screenresolution list 04:30:58 INFO - Available Modes on Display 0 04:30:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:58 INFO - Intel Iris: 04:30:58 INFO - Chipset Model: Intel Iris 04:30:58 INFO - Type: GPU 04:30:58 INFO - Bus: Built-In 04:30:58 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:58 INFO - Vendor: Intel (0x8086) 04:30:58 INFO - Device ID: 0x0a2e 04:30:58 INFO - Revision ID: 0x0009 04:30:58 INFO - Displays: 04:30:58 INFO - Display: 04:30:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:58 INFO - Display Serial Number: 1600x1200 60 04:30:58 INFO - Main Display: Yes 04:30:58 INFO - Mirror: Off 04:30:58 INFO - Online: Yes 04:30:58 INFO - Rotation: Supported 04:30:58 INFO - Return code: 0 04:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:58 INFO - Python 2.7.3 04:30:58 INFO - Return code: 0 04:30:58 INFO - grabbing minidump binary from tooltool 04:30:58 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')]} 04:30:58 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 0x101a80d30>, '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 0x1019a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101a75be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:58 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 04:30:58 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 04:30:58 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 04:30:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:58 INFO - Return code: 0 04:30:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 04:30:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 04:30:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Erl7FcPxcP/Render', 04:30:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:58 INFO - 'HOME': '/Users/cltbld', 04:30:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:58 INFO - 'LOGNAME': 'cltbld', 04:30:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:58 INFO - 'MOZ_NO_REMOTE': '1', 04:30:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:58 INFO - 'NO_EM_RESTART': '1', 04:30:58 INFO - 'PAGER': '/bin/cat', 04:30:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:58 INFO - 'PWD': '/builds/slave/test', 04:30:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:58 INFO - 'SHELL': '/bin/bash', 04:30:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DRQlQHU0YN/Listeners', 04:30:58 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 04:30:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:58 INFO - 'USER': 'cltbld', 04:30:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:58 INFO - 'XPC_FLAGS': '0x0', 04:30:58 INFO - 'XPC_SERVICE_NAME': '0', 04:30:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 04:30:58 INFO - mozversion application_buildid: 20170201030207 04:30:58 INFO - mozversion application_changeset: 1d025ac534a6333a8170a59a95a8a3673d4028ee 04:30:58 INFO - mozversion application_display_name: Nightly 04:30:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:58 INFO - mozversion application_name: Firefox 04:30:58 INFO - mozversion application_remotingname: firefox 04:30:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:30:58 INFO - mozversion application_vendor: Mozilla 04:30:58 INFO - mozversion application_version: 54.0a1 04:30:58 INFO - mozversion platform_buildid: 20170201030207 04:30:58 INFO - mozversion platform_changeset: 1d025ac534a6333a8170a59a95a8a3673d4028ee 04:30:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:30:58 INFO - mozversion platform_version: 54.0a1 04:30:58 INFO - using testdate: 1485952258 04:30:58 INFO - actual date: 1485952258 04:30:58 INFO - starting webserver on 'localhost:51817' 04:30:58 INFO - SUITE-START | Running 2 tests 04:30:58 INFO - TEST-START | dromaeo_css 04:30:58 INFO - operating with platform_type : mac_ 04:30:58 INFO - Initialising browser for dromaeo_css test... 04:30:58 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpqqCVp8/profile http://localhost:51817/getInfo.html) 04:31:01 INFO - PROCESS | 1915 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:31:01 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 04:31:01 INFO - PROCESS | 1915 | colorDepth:24 04:31:01 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 04:31:01 INFO - PROCESS | 1915 | __metrics 04:31:01 INFO - PROCESS | 1915 | 1485952261592 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:31:01 INFO - PROCESS | 1915 | 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" 04:31:01 INFO - PROCESS | 1915 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:31:02 INFO - TEST-INFO | 1915: exit 0 04:31:02 INFO - Browser initialized. 04:31:02 INFO - Running cycle 1/1 for dromaeo_css test... 04:31:02 INFO - TEST-INFO | started process 1918 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpqqCVp8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:14 INFO - PROCESS | 1918 | RSS: Main: 237109248 04:31:14 INFO - PROCESS | 1918 | 04:34:18 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51817/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51817/tests/dromaeo/cssquery-ext.html) 04:34:19 INFO - PROCESS | 1918 | RSS: Main: 370401280 04:34:19 INFO - PROCESS | 1918 | 04:36:03 INFO - PROCESS | 1918 | ************************* 04:36:03 INFO - PROCESS | 1918 | A coding exception was thrown and uncaught in a Task. 04:36:03 INFO - PROCESS | 1918 | 04:36:03 ERROR - PROCESS | 1918 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:03 INFO - PROCESS | 1918 | Full stack: 04:36:03 INFO - PROCESS | 1918 | ************************* 04:37:22 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51817/tests/dromaeo/cssquery-ext.html (next: http://localhost:51817/tests/dromaeo/cssquery-jquery.html) 04:37:23 INFO - PROCESS | 1918 | RSS: Main: 367329280 04:37:23 INFO - PROCESS | 1918 | 04:40:25 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51817/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51817/tests/dromaeo/cssquery-mootools.html) 04:40:26 INFO - PROCESS | 1918 | RSS: Main: 359436288 04:40:26 INFO - PROCESS | 1918 | 04:43:29 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51817/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51817/tests/dromaeo/cssquery-prototype.html) 04:43:29 INFO - PROCESS | 1918 | RSS: Main: 342110208 04:43:29 INFO - PROCESS | 1918 | 04:46:33 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51817/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51817/tests/dromaeo/cssquery-yui.html) 04:46:33 INFO - PROCESS | 1918 | RSS: Main: 327835648 04:46:33 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | Cycle 1(1): loaded http://localhost:51817/tests/dromaeo/cssquery-yui.html (next: http://localhost:51817/tests/dromaeo/cssquery-dojo.html) 04:49:37 INFO - PROCESS | 1918 | RSS: Main: 429789184 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | __start_tp_report 04:49:37 INFO - PROCESS | 1918 | _x_x_mozilla_page_load 04:49:37 INFO - PROCESS | 1918 | _x_x_mozilla_page_load_details 04:49:37 INFO - PROCESS | 1918 | |i|pagename|runs| 04:49:37 INFO - PROCESS | 1918 | |0;dojo.html;2852.9714702852966;3309.172706823294;3324.368370009698;3326.9001929942096;3336.115929278743;5578.470045345685;5651.095824668054;5660.660360378375;5696.174054762062;5697.088465845465;5623.26897503325;5663.065594176966;5793;5803.100519419486;5805.651660900349;5110.616703747215;5111.182210846266;5149.536541711245;5178.611604129686;5205.9219111713355;5271.551918086948;5294.2058691196435;5298.09932311508;5300.522952934235;5304.734763261841;5050.292958985745;5269.683818970874;5273.499017593323;5314.681119132864;5316.946830531684;5084.872878178038;5124.871878203037;5127.9230811537855;5146.3824341078935;5206.088934436496;4600.7929643166135;5169.431362550116;5186.507281808223;5196.58427325813;5242.816501422432;5701.6864072476;5705.144228365737;5711.228984087136;5760.711964401805;5797.8550536236935;6096.048728708478;6167.814965551041;6171.722272497748;6257.311695713468;6270.153529273535;5556.86107847303;6082.726277317532;6215.56491045627;6240.906386404207;6266.028765541349;5863.472287494061;5903;5906.7637294508595;5920.289565252154;6002.939970600281;6164;6274.403931626488;6311;6313;6316.2736285326855;6116.618888358023;6137.478314343241;6149.938500615026;6230.968845155836;6269.467095296951;6139.0144609005865;6247.812565623039;6249.7187626556915;6254.437100660963;6262.937370626236;6117.562731150874;6138.707824479944;6144.433169541739;6152.692365381713;6196.039613859859;6266.467350275187;6273.933431316687;6288.056791481223;6288.465480434214;6290.433860952537;4883.682560633548;4921.901561968741;4944.1100601892;4971.502849715;4972.7513624318635;4468.374427580141;4472.172648060119;4494.07871386365;4494.10117976406;4538.523455037173;5800.100984347433;5813.7383817728305;5816.389279125631;5820.185174075633;5822.475977162076;6150.753969841246;6180.536459765535;6224.502039836802;6231.875362492725;6254.843628909313;5789.421057894177;5821.15593238978;5827.679477628686;5847.590668653153;5850.151727999416;5467.72661366938;5485.835424937259;5488.121900495901;5490.945090549043;5496.917546236809;6131.693415329305;6184.381561843779;6333.303336632875;6356.269029061717;6371.49028077762;6124.411449640054;6125.540584456095;6227.19046523949;6234.501239900887;6250.593711408744;6334.398232168121;6359.17330747;6365.427111559982;6369.808905732835;6387;6190.938090619216;6197.504199664107;6232.532560057924;6234.657093859882;6244.46922011616;6143.051335359142;6182;6187.474064704551;6194.132821404917;6229.345918678655;5991.011483105413;6137.836677219377;6150.969245153746;6168.8766224674355;6176.28972668131;5956.8808623828145;5994.3705910878725;6049.788257411145;6118.5411094169;6152;5931.9110213345975;5932.762689492372;5948.375420580776;5954.255718035246;5967.850803729942;6134.78528251509;6137.907931381123;6145.187169784835;6149.016157414792;6160.969195153995;6015.909761353496;6020.729067192075;6027.939720602738;6034.909476357946;6064.848378790565 04:49:37 INFO - PROCESS | 1918 | |1;ext.html;448.67695259413233;455.50122615735756;480.91313631193515;498.61413138134037;499.2561083984862;39573.60426395735;42428.78785606068;42429.36355954659;42494.362584561255;42580.14839703204;42772;42856;43338.566614333846;43460.78269608648;43571.56428435715;8442.957785211065;8711.782205444882;9136.680216192435;9369;9386.20217281532;11293;11607.883921160765;11609.651710448657;11614.709632259219;11645.592404265852;11387.54449822006;11463.140264480156;11463.140264480156;11468.25456345339;11488.770224595502;8486.575671216415;8706.782330441727;11150.442477876073;11404.714882127972;11456.198066135416;11110.666679999533;11376.829347559793;11390.316581005167;11395.658130256106;11405.714857128554;41609.79195104035;41975.580244197474;41995.37006944898;42001.36997945003;42061;4018.9349822297113;4042.7776472294013;4042.8584999524874;4071.1043570414463;4224.408582798411;30775.922842700624;31016;31939.688142100753;31944.680553194405;32028.199295017577;3089.629244490653;3188.855412701743;3225.3871764364694;3228.4350238708134;3315.5192497087787;344547;344918;346564;464518;465768;61375;61843.6907815458;64287.35712642907;64453.67773161198;64754.67622661951;68499.65750171218;68544.31455685479;68602;68826.65586672135;68856.311436886;16054;16315.347386104422;16330;16352.182390880645;16389.934654247434;11288.887111128948;11585;11597.014253788522;12418.937905310415;12576.937115314548;17803.73294400585;18369.17338719734;18391.72412413815;18396.080195990147;18409;6051.394860513913;6274.905876411857;7187.31720486553;7200.243974382719;7271.89092163618;845.6659619450282;863.8963324401112;880.8854848869606;896.9237614802815;898.793277546168;29062.70937290642;29983;30263.44067212082;30558.27717902058;30890.918817841266;994.2443742755557;1043.634727845248;1048.3500229857384;1076.9192310576739;1078.5308390850005;15123.470678526199;15510.689786204439;15552.688946221238;15635.921820390826;15936.840631593768;565658;572132;573107;574034;576013;61640.38359616436;61873.690631547455;61874;61875.6906215466;61916;1385.5635474825397;1430.7281616493042;1446.2985452055746;1447.1317209674112;1450.2531196433845;28656.856715717124;29190.124296271148;29237.7076229235;29277.85361073181;30035.399292014474;42778.786106069274;53066;53080.40758777243;53229.467705324;53256.73371633117;491362;495327;496393;497373;498221;26540.867295663396;26772.59841102346;26988.190354289403;27086.458270834868;27188.864055679594;39020.30043855239;45449.772751136035;48684.513154868;48699.5130048695;49438.50561494339;36331.844569008186;37030;37433.43849842309;41180.38229426621;41971.160576788905;30356.54465183068;30591.847040764653;40135;41162.38256426216;41435.585644143175;38126.809365954105;38365;38921.41617875677;39257.803710981265;39428;26672.866170705907;32107.39463026886;33565;34436.82781586076;34897;24433;25178.874105629355;25467.23744462462;25520.872395637904;25715.485690285714 04:49:37 INFO - PROCESS | 1918 | |2;jquery.html;365.0617911966244;388.32237745134734;391.10437099043173;399.64232012348947;423.4199147168378;15109.24453777311;15474.38102475901;18714.53213669659;20559.280425185123;21128.788712112873;20838.374848754524;21153.57692846146;21191.35006447776;21434.9282535873;21546;5643.576731745115;5765.481106700396;5925.614835035731;5982.730777115018;5991.340952495222;3842.4812650292133;5966.701664916758;6017.39826017397;6018;6020.698965051752;4045.8786236412957;5607.327120745516;5825.650460972334;5892.057270836667;5947.078202878561;5753.165790960329;5931.31789844167;5944.643321400687;5948.702564871739;5950.523958083343;5823.767049318065;5826.679532625704;5829.7376618052285;5841.211436456067;5842.824715258549;13567.253801040939;13585.59243222705;13676.111052781538;13779.862201378059;13792.103513271606;2580.2001379572393;2597.196298796698;2607.647967524379;2616.2543675052707;2619.3975385661215;10073.748156296078;10193.08262256393;10673.519691613817;11874.762504749942;16101.838981610268;1482.303317440812;1844.5466439360932;1856.0441372693074;1870.3360307090923;1896.7344571759966;115678.42160789142;122274;125948;133275.00087498792;135915.32042339724;8176.3867709922;9776.608935642495;10068;10158.441285729356;10561.735956601147;12576.37118144089;12706.428210730539;12717.473903131684;12718.491260349483;12731.93634031824;7180.851063829762;8010.279074883289;8227.794305142419;8257.504549727035;8422;8038.598070096472;8196.34429245659;8377.83244335122;8571.271441927382;8804.383693141546;3396.949045764266;4023.7183397162503;4036.8788936331957;4043.2317859606583;4045.6763458923215;1986.2352994097378;2017.9697304540443;2031.380428969149;2032.5325175209784;2034.1659919433257;991.6033586565432;1000.6397696829135;1010.6260683546988;1026.6919924022764;1031.1029404418205;20538.486537836376;20943.8952805235;21267.149314027574;21269.46826329354;21280.829554374657;866.2420382165606;941.8392054158171;990.760880410278;1042.9426381549088;1055.456439933435;9218;9689.32174747775;10240;10286.228532860065;10756.781057994936;680119;680128;723445;735365;757001;116429;125382;126157;129576.3696086702;150950;989.5052473763119;991.3754334769194;1031.128696251666;1032.0711359776262;1034.7775228325795;19496.927668978344;19663;19844.404667859988;19868.50328741793;19898.60202795965;41054;50664.240036400224;56032.71983640056;57583;60041.39958600358;78872.60563697145;82403;84079;87959;90383;8160.632771525415;8189.262966333059;8313.459625124347;8657.567121643766;8674.653013879373;12393;13041.217526948414;13266.46934122663;13375.799363009757;13540.661483462993;10864.891351086388;11234.898930433637;11433.314001159957;11530.365829879102;11665.59170429031;10701.625443109453;10892.673219803419;11002.119830413707;11111;11512.94243528777;11110.833662110103;11602.25966105091;11646.70883227926;11843.58547450835;11982.400879955792;8229.094799572162;8407.747767566983;8467.668048395077;8623.267022303175;8650.697225597074;9592.599569696342;9595.664151754656;11083.889161108285;11342.489587968395;11571.958523732905 04:49:37 INFO - PROCESS | 1918 | |3;mootools.html;1383.3014327764479;1408.133997591481;1429.3996521460986;1435.7774544945537;1436.8419473857873;8009;8409.453385529936;8434.409591328595;8443.66225350986;8455.408121431503;7442.590657513834;7636.503627264224;7643.044619422572;7975.361971042303;8224.753257402273;2253.2001139595413;2282.440802003503;2303.53929214157;2304.3087073877855;2304.4008557774932;2261.2989973108306;2296.5062511560013;2303.5968705476557;2309.7343805462333;2314.0628045641456;2021.4643119573325;2093.748750149984;2264.728232612081;2294.736105347881;2295.1393227539675;2211.0824008036648;2284.12061356379;2284.303287497304;2294.7705229477087;2297.322289924476;2224.9555008899733;2283.3720726800098;2288.3706980580305;2292.86242825431;2294.346111358255;8278.544680042596;8356.456830306011;8360.247577717973;8366.247037766569;8377.9946406431;5012.373453318335;5182.481751824825;5182.533571978503;5188.066148093341;5192.1432963560965;3295.96704032959;3413.4709120086427;3460.2560449503226;3484.6341134181052;3492.109512074405;1110.5946329589722;1199.3223828536936;1212.969675758113;1258.4399942025802;1269.9776679772779;10488;10531.894681053169;10561.68314950545;10702.892971070267;10713.982171693755;4151.4395556599475;4356.607905288539;4429.478564541198;4439.66702497314;4469.8710902368475;8637.913620863837;8641.438306510183;8644.394892357475;8652.307815374756;8654.524001179996;5076.923846142311;5082.161443361878;5083.110455670243;5091.287219789232;5101.540861322499;4655;4690.85074156827;4697.812087516461;4729.125111854318;4780.760961951889;3138.3095718941795;3590.8743193988607;3612.3317186320614;3644.161842776123;3680.9631903681156;1123.5449642894641;1141.8743938166795;1144.347721798567;1144.5879483385975;1148.5004023249912;757.806759276381;806.4999700185922;882.5234373438271;895.9865602015975;927.8958039932459;5199.766010529535;5595.132754423071;5675.233843431167;5712.628679135926;5952.404759524013;782.7496692957296;917.124146440148;945.7115579748105;977.7555611097225;986.2061040862175;3600.6039335673054;3644.453332000221;3663.2673465307043;3718.8326525306425;3732.8320225589914;9714.854277185848;10062.798744025078;10086.193104551618;10093.545790439448;10104.797904041878;9936.602535898628;10022.3485473444;10036.548355324028;10047.24645651579;10081.49592520371;7629.2752188542;7647.847043059108;7661.6552255147535;7702.306792388712;7818.804529886685;5349.277847490539;5381.2197231402015;5459.590530710292;5484.424135465719;5664.603477756517;9053.366264361432;9149.542522873962;9190.862137067816;9199.26405887514;9220.077992200726;8857.512836794038;9372.34393592442;9379.484128373006;9398.859017114612;9401.85897211556;6518.380753828379;6735.360140786617;6754.898676519954;6802.965985170043;6821.3519715626935;7447.962760186165;7629.694812207672;7821.569813660303;7873.173316801883;7929;7099.929000709927;7109.964450177716;7141.714331426892;7175.282471752783;7285.490015698851;6364.713587888464;6923.584584924921;7005.579665220103;7033.29667033313;7061.505694601328;5315.468453154654;6384.553081284265;6384.553081284451;6386.329435409401;6390.968045159744;4813.302071199727;4881.707097574157;4947.604191664586;5018.573421259234;5049.990001999541;4868.659193856396;4963.975180124076;5054.039732450822;5083.46623604531;5166.819161329336 04:49:37 INFO - PROCESS | 1918 | |4;prototype.html;336.983150842458;351.7432274439659;352.73192373795905;362.932857421377;368.8045335971936;5283.196098808851;5325.893482130355;5353.518183363496;5360.973195134024;5408.756605952732;4381.496127945279;4926.901461970759;5084.542391184793;5118.539331460168;5239.711815850127;4126.649234815044;4134.235166494208;4161.875143745678;4173.979130104361;4207.600277973587;4086.570910054446;4109.979450102745;4130.524989626201;4142.627163555295;4151.045259590296;3260.3805276997414;3336.549565808621;4123.330051329216;4143.08852052548;4147.958520414787;3420.7776494527775;3420.880269190566;3453.2575496268173;4129.752214867118;4142.92284006159;3370.2753907909673;3644.1982763791925;3660.9267814643827;3663.249033948034;3687.7049836013052;4276.572342765729;5073.043677086808;5144.305518754958;5154.226865970097;5222.608304377149;4092.4679791627195;4231.968655045595;4741.57325840676;4821.566059054702;4827.61379089672;4927.802887884481;5589.484731870633;5593.356763972155;5601.551875849922;5626.971865140648;4070.674346052309;4078.612531809503;4102.548719640837;4103.569125241836;4113.424120623115;5282.596418629552;5470.890582188374;5783.942160578383;5796.0440175510375;5810.883782324415;3449.3618680544178;3668.5047518584647;4123.958760412417;4138.689598280141;4140.834366625301;3502.667246611568;4186.5604111568455;4198.790060496963;4199;4232.174725928415;4006.1787333596544;4210.8420184448905;4399.868003959887;4558.037275770709;4622.375979242759;4489.865304040884;4639.234526303122;4950.950490495121;5616.775328986876;5646.2942132233475;1114.9721256968478;1124.6344937895217;1189.2102385909325;1235.4440501774059;1270.790319597256;683.5522732610049;698.972510409697;811.338758911497;811.6875003123722;865.5715420866585;4183.790810459464;4358.738475691469;4698.929516057262;4928.950710492849;4959.454460009396;4875.074345633526;5296.278709419454;5363.345144153669;5417.83746487606;5506.284183056177;4508.07584445188;4616.399868017136;4868.807142250172;4918.901621967612;5066.46802085783;2544.5572250639225;2579.761714377072;2904.259413849497;2905.288204389938;2931.5162998105075;4794.904101917942;4866.829660961849;4990.850274491771;5638.23726374082;5723.136882604225;5250.107481728039;5360;5470.264867566217;5622.85942851432;5654.321481422256;5636.859078522905;5701.4298570142655;5726.2555867737765;5747.482726554546;5765.769369225268;4738.573528382462;4990.201567749143;5344.225087362389;5453.863653408538;5495.258140151188;4771.284307353924;5125.974370128126;5326.760295786621;5387.407385187548;5478.506934375925;5041.647084704182;5210.887139055239;5496.615236933376;5578.386377498553;5744.368119506935;3278.737685990863;3312.354090952241;3474.6004209516227;3497.632748561364;3569.928601427905;4637.536246375335;5055.32382546136;5230.503102205284;5455.618106732491;5473.8357849264585;4809.278608208797;5276.9472305278;5305.946940530545;5344.518993290622;5358.517733404013;4795.39354316308;4915.655904086822;5154.020736060136;5189.506996835295;5230.372355317386;5154.175331946871;5181.585473162213;5257.9221259642445;5258.211268309784;5263.394709608444;4629.768511574475;5265.420803711665;5342.652727572696;5433.972830135824;5509.47659972302;4157.168566286694;4354.825806967686;4907.950920490892;5092.5740792578135;5569.718964638075 04:49:37 INFO - PROCESS | 1918 | |5;yui.html;708.2457183099995;721.2246834652748;736.8689062289387;786.1548835002374;845.9027211870634;15058.698826023472;15932.761008584881;15954.28205730742;15955.122468264255;15972.68054638908;14752.778708319356;14894.47869324574;14914.850851491508;14922.626934326647;14933.402663893432;678.6572780745742;704.6265479295981;709.9503034787567;870.8171284030361;881.0704706534603;863.3999370437548;882.1266945723719;882.3691060891462;886.5079880666227;888.537960260664;890.5769759364282;891.4606662968907;932.4358762948386;937.2221056523103;937.8921424036255;717.8061923280685;742.8662840688673;885.8139790643972;886.5965985476593;886.7605746448487;671.8186089755787;693.9971740834496;699.3041923286312;700.0654126740807;701.6702149989496;15070.547883563511;15439.845601543953;15489.612759680986;15491.302891369915;15517.612059698486;11606.30362178272;13918.164910105426;13965.441382344694;14032.03169326014;14219.289035548285;12526.74946501074;12795.744085118245;12852.421641026176;12975.416106275241;13163.144395614254;4478.350639157336;4481.170983368054;4529.909401811979;4536.4102666653125;4646.9767651161865;101630.49184754102;105548;106157.46921265422;108084;108563;11024.44877756109;11140.275882067639;11375.488103035217;11389.601363952223;11618.07055435563;18802.81197188038;18959.241630334633;22918.854057297067;22923.656145157835;22928.541429171324;7689.692412303445;7989.800254993555;7995;8014.79963000918;8040.954675892097;4266.168097221074;4284.978575107167;4334.978325108417;4401.449818772653;4484.798184081724;3405.540252065989;3493.633168517269;3502.404591219499;3510.297940411877;3542.0790594445116;618.3136718242747;620.6710443464971;626.7931582577738;635.1742734445203;641.5990006246096;568.4003376437815;582.3390451837196;610.9083637454329;662.3178126529682;671.9059331693567;9866.351811245544;10059.949700251453;10162.339447935861;10541.736456588647;10567.894321056845;554.9445055494499;579.3135134865198;594.3432507079701;635.9887778432314;704.5983789240084;4423.933640995388;4945.332380128709;4956.281339205867;4966.478519755446;4996.375453068366;77055;103485;104967.20992143496;107030;107276.46361768141;89668;93233.5338323304;93488.66278343096;94536.58195127078;94687;80640.59679701563;81146;82136;82187.5890620543;82296.9999999988;9391.389559678599;9615.855762163434;9652.420854748738;9661.71014869555;9672.70981870545;41375.37936930888;49821;49934;50276;50950.23574646309;67872.3212767866;69142.3085769136;70296.6485167571;71973.64013179901;72180.27819721735;9874.85187722199;11467.31196128235;11713.707157320794;11789.41052947332;12491.188072775241;10927.945360273467;11614.419279035845;11701.2979221247;11881.702957426134;12189.268643881041;10798.622048228275;11954.462049207632;12053.939730301292;12115.939420302842;12309.630711078682;11719.104654817045;12016.33910134951;12110.878891210976;12160.817587736014;12272.079594030305;11822.940885295518;11887.76224475523;11914.702132446759;11940.701482463008;12299.815502767644;9965.750856228653;9984.550695218877;10096.798064038825;10222.080012798586;10557.472126393803;8371.62327695243;9836.5081745911;9880.011998800062;9886.209103271578;9887.752806179904 04:49:37 INFO - PROCESS | 1918 | __end_tp_report 04:49:37 INFO - PROCESS | 1918 | __start_cc_report 04:49:37 INFO - PROCESS | 1918 | _x_x_mozilla_cycle_collect,1661 04:49:37 INFO - PROCESS | 1918 | __end_cc_report 04:49:37 INFO - PROCESS | 1918 | __startTimestamp1485953377515__endTimestamp 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | ------- Summary: start ------- 04:49:37 INFO - PROCESS | 1918 | Number of tests: 6 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | [#0] dojo.html Cycles:165 Average:5753.98 Median:5994.37 stddev:653.23 (10.9%) stddev-sans-first:614.31 04:49:37 INFO - PROCESS | 1918 | Values: 2853.0 3309.2 3324.4 3326.9 3336.1 5578.5 5651.1 5660.7 5696.2 5697.1 5623.3 5663.1 5793.0 5803.1 5805.7 5110.6 5111.2 5149.5 5178.6 5205.9 5271.6 5294.2 5298.1 5300.5 5304.7 5050.3 5269.7 5273.5 5314.7 5316.9 5084.9 5124.9 5127.9 5146.4 5206.1 4600.8 5169.4 5186.5 5196.6 5242.8 5701.7 5705.1 5711.2 5760.7 5797.9 6096.0 6167.8 6171.7 6257.3 6270.2 5556.9 6082.7 6215.6 6240.9 6266.0 5863.5 5903.0 5906.8 5920.3 6002.9 6164.0 6274.4 6311.0 6313.0 6316.3 6116.6 6137.5 6149.9 6231.0 6269.5 6139.0 6247.8 6249.7 6254.4 6262.9 6117.6 6138.7 6144.4 6152.7 6196.0 6266.5 6273.9 6288.1 6288.5 6290.4 4883.7 4921.9 4944.1 4971.5 4972.8 4468.4 4472.2 4494.1 4494.1 4538.5 5800.1 5813.7 5816.4 5820.2 5822.5 6150.8 6180.5 6224.5 6231.9 6254.8 5789.4 5821.2 5827.7 5847.6 5850.2 5467.7 5485.8 5488.1 5490.9 5496.9 6131.7 6184.4 6333.3 6356.3 6371.5 6124.4 6125.5 6227.2 6234.5 6250.6 6334.4 6359.2 6365.4 6369.8 6387.0 6190.9 6197.5 6232.5 6234.7 6244.5 6143.1 6182.0 6187.5 6194.1 6229.3 5991.0 6137.8 6151.0 6168.9 6176.3 5956.9 5994.4 6049.8 6118.5 6152.0 5931.9 5932.8 5948.4 5954.3 5967.9 6134.8 6137.9 6145.2 6149.0 6161.0 6015.9 6020.7 6027.9 6034.9 6064.8 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | [#1] ext.html Cycles:180 Average:63965.47 Median:38245.90 stddev:131377.09 (343.5%) stddev-sans-first:131659.08 04:49:37 INFO - PROCESS | 1918 | Values: 448.7 455.5 480.9 498.6 499.3 39573.6 42428.8 42429.4 42494.4 42580.1 42772.0 42856.0 43338.6 43460.8 43571.6 8443.0 8711.8 9136.7 9369.0 9386.2 11293.0 11607.9 11609.7 11614.7 11645.6 11387.5 11463.1 11463.1 11468.3 11488.8 8486.6 8706.8 11150.4 11404.7 11456.2 11110.7 11376.8 11390.3 11395.7 11405.7 41609.8 41975.6 41995.4 42001.4 42061.0 4018.9 4042.8 4042.9 4071.1 4224.4 30775.9 31016.0 31939.7 31944.7 32028.2 3089.6 3188.9 3225.4 3228.4 3315.5 344547.0 344918.0 346564.0 464518.0 465768.0 61375.0 61843.7 64287.4 64453.7 64754.7 68499.7 68544.3 68602.0 68826.7 68856.3 16054.0 16315.3 16330.0 16352.2 16389.9 11288.9 11585.0 11597.0 12418.9 12576.9 17803.7 18369.2 18391.7 18396.1 18409.0 6051.4 6274.9 7187.3 7200.2 7271.9 845.7 863.9 880.9 896.9 898.8 29062.7 29983.0 30263.4 30558.3 30890.9 994.2 1043.6 1048.4 1076.9 1078.5 15123.5 15510.7 15552.7 15635.9 15936.8 565658.0 572132.0 573107.0 574034.0 576013.0 61640.4 61873.7 61874.0 61875.7 61916.0 1385.6 1430.7 1446.3 1447.1 1450.3 28656.9 29190.1 29237.7 29277.9 30035.4 42778.8 53066.0 53080.4 53229.5 53256.7 491362.0 495327.0 496393.0 497373.0 498221.0 26540.9 26772.6 26988.2 27086.5 27188.9 39020.3 45449.8 48684.5 48699.5 49438.5 36331.8 37030.0 37433.4 41180.4 41971.2 30356.5 30591.8 40135.0 41162.4 41435.6 38126.8 38365.0 38921.4 39257.8 39428.0 26672.9 32107.4 33565.0 34436.8 34897.0 24433.0 25178.9 25467.2 25520.9 25715.5 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | [#2] jquery.html Cycles:180 Average:38360.25 Median:21357.88 stddev:118892.55 (556.7%) stddev-sans-first:119191.84 04:49:37 INFO - PROCESS | 1918 | Values: 365.1 388.3 391.1 399.6 423.4 15109.2 15474.4 18714.5 20559.3 21128.8 20838.4 21153.6 21191.4 21434.9 21546.0 5643.6 5765.5 5925.6 5982.7 5991.3 3842.5 5966.7 6017.4 6018.0 6020.7 4045.9 5607.3 5825.7 5892.1 5947.1 5753.2 5931.3 5944.6 5948.7 5950.5 5823.8 5826.7 5829.7 5841.2 5842.8 13567.3 13585.6 13676.1 13779.9 13792.1 2580.2 2597.2 2607.6 2616.3 2619.4 10073.7 10193.1 10673.5 11874.8 16101.8 1482.3 1844.5 1856.0 1870.3 1896.7 115678.4 122274.0 125948.0 133275.0 135915.3 8176.4 9776.6 10068.0 10158.4 10561.7 12576.4 12706.4 12717.5 12718.5 12731.9 7180.9 8010.3 8227.8 8257.5 8422.0 8038.6 8196.3 8377.8 8571.3 8804.4 3396.9 4023.7 4036.9 4043.2 4045.7 1986.2 2018.0 2031.4 2032.5 2034.2 991.6 1000.6 1010.6 1026.7 1031.1 20538.5 20943.9 21267.1 21269.5 21280.8 866.2 941.8 990.8 1042.9 1055.5 9218.0 9689.3 10240.0 10286.2 10756.8 680119.0 680128.0 723445.0 735365.0 757001.0 116429.0 125382.0 126157.0 129576.4 150950.0 989.5 991.4 1031.1 1032.1 1034.8 19496.9 19663.0 19844.4 19868.5 19898.6 41054.0 50664.2 56032.7 57583.0 60041.4 78872.6 82403.0 84079.0 87959.0 90383.0 8160.6 8189.3 8313.5 8657.6 8674.7 12393.0 13041.2 13266.5 13375.8 13540.7 10864.9 11234.9 11433.3 11530.4 11665.6 10701.6 10892.7 11002.1 11111.0 11512.9 11110.8 11602.3 11646.7 11843.6 11982.4 8229.1 8407.7 8467.7 8623.3 8650.7 9592.6 9595.7 11083.9 11342.5 11572.0 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | [#3] mootools.html Cycles:180 Average:5346.58 Median:5087.38 stddev:2920.39 (57.4%) stddev-sans-first:2913.39 04:49:37 INFO - PROCESS | 1918 | Values: 1383.3 1408.1 1429.4 1435.8 1436.8 8009.0 8409.5 8434.4 8443.7 8455.4 7442.6 7636.5 7643.0 7975.4 8224.8 2253.2 2282.4 2303.5 2304.3 2304.4 2261.3 2296.5 2303.6 2309.7 2314.1 2021.5 2093.7 2264.7 2294.7 2295.1 2211.1 2284.1 2284.3 2294.8 2297.3 2225.0 2283.4 2288.4 2292.9 2294.3 8278.5 8356.5 8360.2 8366.2 8378.0 5012.4 5182.5 5182.5 5188.1 5192.1 3296.0 3413.5 3460.3 3484.6 3492.1 1110.6 1199.3 1213.0 1258.4 1270.0 10488.0 10531.9 10561.7 10702.9 10714.0 4151.4 4356.6 4429.5 4439.7 4469.9 8637.9 8641.4 8644.4 8652.3 8654.5 5076.9 5082.2 5083.1 5091.3 5101.5 4655.0 4690.9 4697.8 4729.1 4780.8 3138.3 3590.9 3612.3 3644.2 3681.0 1123.5 1141.9 1144.3 1144.6 1148.5 757.8 806.5 882.5 896.0 927.9 5199.8 5595.1 5675.2 5712.6 5952.4 782.7 917.1 945.7 977.8 986.2 3600.6 3644.5 3663.3 3718.8 3732.8 9714.9 10062.8 10086.2 10093.5 10104.8 9936.6 10022.3 10036.5 10047.2 10081.5 7629.3 7647.8 7661.7 7702.3 7818.8 5349.3 5381.2 5459.6 5484.4 5664.6 9053.4 9149.5 9190.9 9199.3 9220.1 8857.5 9372.3 9379.5 9398.9 9401.9 6518.4 6735.4 6754.9 6803.0 6821.4 7448.0 7629.7 7821.6 7873.2 7929.0 7099.9 7110.0 7141.7 7175.3 7285.5 6364.7 6923.6 7005.6 7033.3 7061.5 5315.5 6384.6 6384.6 6386.3 6391.0 4813.3 4881.7 4947.6 5018.6 5050.0 4868.7 4964.0 5054.0 5083.5 5166.8 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | [#4] prototype.html Cycles:180 Average:4388.46 Median:4891.51 stddev:1322.17 (27.0%) stddev-sans-first:1290.44 04:49:37 INFO - PROCESS | 1918 | Values: 337.0 351.7 352.7 362.9 368.8 5283.2 5325.9 5353.5 5361.0 5408.8 4381.5 4926.9 5084.5 5118.5 5239.7 4126.6 4134.2 4161.9 4174.0 4207.6 4086.6 4110.0 4130.5 4142.6 4151.0 3260.4 3336.5 4123.3 4143.1 4148.0 3420.8 3420.9 3453.3 4129.8 4142.9 3370.3 3644.2 3660.9 3663.2 3687.7 4276.6 5073.0 5144.3 5154.2 5222.6 4092.5 4232.0 4741.6 4821.6 4827.6 4927.8 5589.5 5593.4 5601.6 5627.0 4070.7 4078.6 4102.5 4103.6 4113.4 5282.6 5470.9 5783.9 5796.0 5810.9 3449.4 3668.5 4124.0 4138.7 4140.8 3502.7 4186.6 4198.8 4199.0 4232.2 4006.2 4210.8 4399.9 4558.0 4622.4 4489.9 4639.2 4951.0 5616.8 5646.3 1115.0 1124.6 1189.2 1235.4 1270.8 683.6 699.0 811.3 811.7 865.6 4183.8 4358.7 4698.9 4929.0 4959.5 4875.1 5296.3 5363.3 5417.8 5506.3 4508.1 4616.4 4868.8 4918.9 5066.5 2544.6 2579.8 2904.3 2905.3 2931.5 4794.9 4866.8 4990.9 5638.2 5723.1 5250.1 5360.0 5470.3 5622.9 5654.3 5636.9 5701.4 5726.3 5747.5 5765.8 4738.6 4990.2 5344.2 5453.9 5495.3 4771.3 5126.0 5326.8 5387.4 5478.5 5041.6 5210.9 5496.6 5578.4 5744.4 3278.7 3312.4 3474.6 3497.6 3569.9 4637.5 5055.3 5230.5 5455.6 5473.8 4809.3 5276.9 5305.9 5344.5 5358.5 4795.4 4915.7 5154.0 5189.5 5230.4 5154.2 5181.6 5257.9 5258.2 5263.4 4629.8 5265.4 5342.7 5434.0 5509.5 4157.2 4354.8 4908.0 5092.6 5569.7 04:49:37 INFO - PROCESS | 1918 | 04:49:37 INFO - PROCESS | 1918 | [#5] yui.html Cycles:180 Average:20419.08 Median:4591.69 stddev:29845.14 (650.0%) stddev-sans-first:29892.17 04:49:37 INFO - PROCESS | 1918 | Values: 708.2 721.2 736.9 786.2 845.9 15058.7 15932.8 15954.3 15955.1 15972.7 14752.8 14894.5 14914.9 14922.6 14933.4 678.7 704.6 710.0 870.8 881.1 863.4 882.1 882.4 886.5 888.5 890.6 891.5 932.4 937.2 937.9 717.8 742.9 885.8 886.6 886.8 671.8 694.0 699.3 700.1 701.7 15070.5 15439.8 15489.6 15491.3 15517.6 11606.3 13918.2 13965.4 14032.0 14219.3 12526.7 12795.7 12852.4 12975.4 13163.1 4478.4 4481.2 4529.9 4536.4 4647.0 101630.5 105548.0 106157.5 108084.0 108563.0 11024.4 11140.3 11375.5 11389.6 11618.1 18802.8 18959.2 22918.9 22923.7 22928.5 7689.7 7989.8 7995.0 8014.8 8041.0 4266.2 4285.0 4335.0 4401.4 4484.8 3405.5 3493.6 3502.4 3510.3 3542.1 618.3 620.7 626.8 635.2 641.6 568.4 582.3 610.9 662.3 671.9 9866.4 10059.9 10162.3 10541.7 10567.9 554.9 579.3 594.3 636.0 704.6 4423.9 4945.3 4956.3 4966.5 4996.4 77055.0 103485.0 104967.2 107030.0 107276.5 89668.0 93233.5 93488.7 94536.6 94687.0 80640.6 81146.0 82136.0 82187.6 82297.0 9391.4 9615.9 9652.4 9661.7 9672.7 41375.4 49821.0 49934.0 50276.0 50950.2 67872.3 69142.3 70296.6 71973.6 72180.3 9874.9 11467.3 11713.7 11789.4 12491.2 10927.9 11614.4 11701.3 11881.7 12189.3 10798.6 11954.5 12053.9 12115.9 12309.6 11719.1 12016.3 12110.9 12160.8 12272.1 11822.9 11887.8 11914.7 11940.7 12299.8 9965.8 9984.6 10096.8 10222.1 10557.5 8371.6 9836.5 9880.0 9886.2 9887.8 04:49:37 INFO - PROCESS | 1918 | -------- Summary: end -------- 04:49:37 INFO - PROCESS | 1918 | 04:49:38 INFO - TEST-INFO | 1918: exit 0 04:49:38 INFO - TEST-OK | dromaeo_css | took 1119271ms 04:49:38 INFO - TEST-START | kraken 04:49:38 INFO - operating with platform_type : mac_ 04:49:38 INFO - Initialising browser for kraken test... 04:49:38 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpcYh0cu/profile http://localhost:51817/getInfo.html) 04:49:40 INFO - PROCESS | 1921 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:49:40 INFO - PROCESS | 1921 | __metrics Screen width/height:1600/1200 04:49:40 INFO - PROCESS | 1921 | colorDepth:24 04:49:40 INFO - PROCESS | 1921 | Browser inner width/height: 1024/689 04:49:40 INFO - PROCESS | 1921 | __metrics 04:49:40 INFO - PROCESS | 1921 | 1485953380615 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:49:40 INFO - PROCESS | 1921 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:49:40 INFO - PROCESS | 1921 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:49:41 INFO - TEST-INFO | 1921: exit 0 04:49:41 INFO - Browser initialized. 04:49:41 INFO - Running cycle 1/1 for kraken test... 04:49:41 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpcYh0cu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:53 INFO - PROCESS | 1923 | RSS: Main: 237805568 04:49:53 INFO - PROCESS | 1923 | 04:49:56 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51817/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:57 INFO - PROCESS | 1923 | RSS: Main: 289120256 04:49:57 INFO - PROCESS | 1923 | 04:50:00 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51817/tests/kraken/driver.html?testName=audio-dft) 04:50:00 INFO - PROCESS | 1923 | RSS: Main: 290762752 04:50:00 INFO - PROCESS | 1923 | 04:50:04 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51817/tests/kraken/driver.html?testName=audio-fft) 04:50:04 INFO - PROCESS | 1923 | RSS: Main: 381136896 04:50:04 INFO - PROCESS | 1923 | 04:50:06 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51817/tests/kraken/driver.html?testName=audio-oscillator) 04:50:07 INFO - PROCESS | 1923 | RSS: Main: 284602368 04:50:07 INFO - PROCESS | 1923 | 04:50:09 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51817/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:09 INFO - PROCESS | 1923 | RSS: Main: 288108544 04:50:09 INFO - PROCESS | 1923 | 04:50:12 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51817/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:13 INFO - PROCESS | 1923 | RSS: Main: 330346496 04:50:13 INFO - PROCESS | 1923 | 04:50:16 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51817/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:16 INFO - PROCESS | 1923 | RSS: Main: 332558336 04:50:16 INFO - PROCESS | 1923 | 04:50:19 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51817/tests/kraken/driver.html?testName=json-parse-financial) 04:50:20 INFO - PROCESS | 1923 | RSS: Main: 330452992 04:50:20 INFO - PROCESS | 1923 | 04:50:22 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51817/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:22 INFO - PROCESS | 1923 | RSS: Main: 305717248 04:50:22 INFO - PROCESS | 1923 | 04:50:24 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:25 INFO - PROCESS | 1923 | RSS: Main: 308695040 04:50:25 INFO - PROCESS | 1923 | 04:50:27 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:28 INFO - PROCESS | 1923 | RSS: Main: 309059584 04:50:28 INFO - PROCESS | 1923 | 04:50:31 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:50:31 INFO - PROCESS | 1923 | RSS: Main: 328867840 04:50:31 INFO - PROCESS | 1923 | 04:50:34 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:50:35 INFO - PROCESS | 1923 | RSS: Main: 322797568 04:50:35 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:51817/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51817/tests/kraken/driver.html?testName=ai-astar) 04:50:37 INFO - PROCESS | 1923 | RSS: Main: 367882240 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | __start_tp_report 04:50:37 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 04:50:37 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 04:50:37 INFO - PROCESS | 1923 | |i|pagename|runs| 04:50:37 INFO - PROCESS | 1923 | |0;ai-astar;113;112;111;112;112;113;111;112;113;112 04:50:37 INFO - PROCESS | 1923 | |1;audio-beat-detection;142;110;109;147;111;110;113;89;137;140 04:50:37 INFO - PROCESS | 1923 | |2;audio-dft;188;181;184;183;182;186;177;183;182;185 04:50:37 INFO - PROCESS | 1923 | |3;audio-fft;76;58;58;89;60;58;58;59;73;59 04:50:37 INFO - PROCESS | 1923 | |4;audio-oscillator;89;69;85;71;68;70;84;67;70;82 04:50:37 INFO - PROCESS | 1923 | |5;imaging-gaussian-blur;85;91;135;98;86;83;87;84;86;97 04:50:37 INFO - PROCESS | 1923 | |6;imaging-darkroom;94;93;93;94;93;94;93;94;101;135 04:50:37 INFO - PROCESS | 1923 | |7;imaging-desaturate;84;87;80;86;72;127;140;96;85;84 04:50:37 INFO - PROCESS | 1923 | |8;json-parse-financial;60;59;59;59;60;60;60;60;60;59 04:50:37 INFO - PROCESS | 1923 | |9;json-stringify-tinderbox;61;74;65;79;73;70;72;73;71;72 04:50:37 INFO - PROCESS | 1923 | |10;stanford-crypto-aes;86;89;87;87;87;85;87;96;105;77 04:50:37 INFO - PROCESS | 1923 | |11;stanford-crypto-ccm;140;141;138;136;166;147;153;135;178;146 04:50:37 INFO - PROCESS | 1923 | |12;stanford-crypto-pbkdf2;174;175;173;172;173;172;148;204;237;190 04:50:37 INFO - PROCESS | 1923 | |13;stanford-crypto-sha256-iterative;71;85;87;86;66;69;67;62;67;66 04:50:37 INFO - PROCESS | 1923 | __end_tp_report 04:50:37 INFO - PROCESS | 1923 | __start_cc_report 04:50:37 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,2038 04:50:37 INFO - PROCESS | 1923 | __end_cc_report 04:50:37 INFO - PROCESS | 1923 | __startTimestamp1485953437582__endTimestamp 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | ------- Summary: start ------- 04:50:37 INFO - PROCESS | 1923 | Number of tests: 14 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:0.74 (0.7%) stddev-sans-first:0.71 04:50:37 INFO - PROCESS | 1923 | Values: 113.0 112.0 111.0 112.0 112.0 113.0 111.0 112.0 113.0 112.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:138.50 stddev:19.17 (13.8%) stddev-sans-first:18.74 04:50:37 INFO - PROCESS | 1923 | Values: 142.0 110.0 109.0 147.0 111.0 110.0 113.0 89.0 137.0 140.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:3.00 (1.6%) stddev-sans-first:2.60 04:50:37 INFO - PROCESS | 1923 | Values: 188.0 181.0 184.0 183.0 182.0 186.0 177.0 183.0 182.0 185.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#3] audio-fft Cycles:10 Average:64.80 Median:59.50 stddev:10.82 (18.2%) stddev-sans-first:10.69 04:50:37 INFO - PROCESS | 1923 | Values: 76.0 58.0 58.0 89.0 60.0 58.0 58.0 59.0 73.0 59.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.50 stddev:8.42 (11.0%) stddev-sans-first:7.38 04:50:37 INFO - PROCESS | 1923 | Values: 89.0 69.0 85.0 71.0 68.0 70.0 84.0 67.0 70.0 82.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:93.20 Median:86.50 stddev:15.59 (18.0%) stddev-sans-first:16.25 04:50:37 INFO - PROCESS | 1923 | Values: 85.0 91.0 135.0 98.0 86.0 83.0 87.0 84.0 86.0 97.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#6] imaging-darkroom Cycles:10 Average:98.40 Median:93.50 stddev:13.08 (14.0%) stddev-sans-first:13.78 04:50:37 INFO - PROCESS | 1923 | Values: 94.0 93.0 93.0 94.0 93.0 94.0 93.0 94.0 101.0 135.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:84.50 stddev:21.81 (25.8%) stddev-sans-first:22.83 04:50:37 INFO - PROCESS | 1923 | Values: 84.0 87.0 80.0 86.0 72.0 127.0 140.0 96.0 85.0 84.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:60.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 04:50:37 INFO - PROCESS | 1923 | Values: 60.0 59.0 59.0 59.0 60.0 60.0 60.0 60.0 60.0 59.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:72.50 stddev:4.94 (6.8%) stddev-sans-first:3.69 04:50:37 INFO - PROCESS | 1923 | Values: 61.0 74.0 65.0 79.0 73.0 70.0 72.0 73.0 71.0 72.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:87.00 stddev:7.37 (8.5%) stddev-sans-first:7.75 04:50:37 INFO - PROCESS | 1923 | Values: 86.0 89.0 87.0 87.0 87.0 85.0 87.0 96.0 105.0 77.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:146.50 stddev:14.06 (9.6%) stddev-sans-first:14.62 04:50:37 INFO - PROCESS | 1923 | Values: 140.0 141.0 138.0 136.0 166.0 147.0 153.0 135.0 178.0 146.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.80 Median:174.50 stddev:24.05 (13.8%) stddev-sans-first:25.34 04:50:37 INFO - PROCESS | 1923 | Values: 174.0 175.0 173.0 172.0 173.0 172.0 148.0 204.0 237.0 190.0 04:50:37 INFO - PROCESS | 1923 | 04:50:37 INFO - PROCESS | 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:70.00 stddev:9.54 (13.6%) stddev-sans-first:10.10 04:50:37 INFO - PROCESS | 1923 | Values: 71.0 85.0 87.0 86.0 66.0 69.0 67.0 62.0 67.0 66.0 04:50:37 INFO - PROCESS | 1923 | -------- Summary: end -------- 04:50:37 INFO - PROCESS | 1923 | 04:50:38 INFO - TEST-INFO | 1923: exit 0 04:50:38 INFO - TEST-OK | kraken | took 60320ms 04:50:38 INFO - SUITE-END | took 1179s 04:50:38 INFO - Completed test suite (00:19:39) 04:50:38 INFO - javascript benchmark 04:50:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7186.420511762289, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2852.9714702852966, 3309.172706823294, 3324.368370009698, 3326.9001929942096, 3336.115929278743, 5578.470045345685, 5651.095824668054, 5660.660360378375, 5696.174054762062, 5697.088465845465, 5623.26897503325, 5663.065594176966, 5793.0, 5803.100519419486, 5805.651660900349, 5110.616703747215, 5111.182210846266, 5149.536541711245, 5178.611604129686, 5205.9219111713355, 5271.551918086948, 5294.2058691196435, 5298.09932311508, 5300.522952934235, 5304.734763261841, 5050.292958985745, 5269.683818970874, 5273.499017593323, 5314.681119132864, 5316.946830531684, 5084.872878178038, 5124.871878203037, 5127.9230811537855, 5146.3824341078935, 5206.088934436496, 4600.7929643166135, 5169.431362550116, 5186.507281808223, 5196.58427325813, 5242.816501422432, 5701.6864072476, 5705.144228365737, 5711.228984087136, 5760.711964401805, 5797.8550536236935, 6096.048728708478, 6167.814965551041, 6171.722272497748, 6257.311695713468, 6270.153529273535, 5556.86107847303, 6082.726277317532, 6215.56491045627, 6240.906386404207, 6266.028765541349, 5863.472287494061, 5903.0, 5906.7637294508595, 5920.289565252154, 6002.939970600281, 6164.0, 6274.403931626488, 6311.0, 6313.0, 6316.2736285326855, 6116.618888358023, 6137.478314343241, 6149.938500615026, 6230.968845155836, 6269.467095296951, 6139.0144609005865, 6247.812565623039, 6249.7187626556915, 6254.437100660963, 6262.937370626236, 6117.562731150874, 6138.707824479944, 6144.433169541739, 6152.692365381713, 6196.039613859859, 6266.467350275187, 6273.933431316687, 6288.056791481223, 6288.465480434214, 6290.433860952537, 4883.682560633548, 4921.901561968741, 4944.1100601892, 4971.502849715, 4972.7513624318635, 4468.374427580141, 4472.172648060119, 4494.07871386365, 4494.10117976406, 4538.523455037173, 5800.100984347433, 5813.7383817728305, 5816.389279125631, 5820.185174075633, 5822.475977162076, 6150.753969841246, 6180.536459765535, 6224.502039836802, 6231.875362492725, 6254.843628909313, 5789.421057894177, 5821.15593238978, 5827.679477628686, 5847.590668653153, 5850.151727999416, 5467.72661366938, 5485.835424937259, 5488.121900495901, 5490.945090549043, 5496.917546236809, 6131.693415329305, 6184.381561843779, 6333.303336632875, 6356.269029061717, 6371.49028077762, 6124.411449640054, 6125.540584456095, 6227.19046523949, 6234.501239900887, 6250.593711408744, 6334.398232168121, 6359.17330747, 6365.427111559982, 6369.808905732835, 6387.0, 6190.938090619216, 6197.504199664107, 6232.532560057924, 6234.657093859882, 6244.46922011616, 6143.051335359142, 6182.0, 6187.474064704551, 6194.132821404917, 6229.345918678655, 5991.011483105413, 6137.836677219377, 6150.969245153746, 6168.8766224674355, 6176.28972668131, 5956.8808623828145, 5994.3705910878725, 6049.788257411145, 6118.5411094169, 6152.0, 5931.9110213345975, 5932.762689492372, 5948.375420580776, 5954.255718035246, 5967.850803729942, 6134.78528251509, 6137.907931381123, 6145.187169784835, 6149.016157414792, 6160.969195153995, 6015.909761353496, 6020.729067192075, 6027.939720602738, 6034.909476357946, 6064.848378790565], "value": 5709.689504610791, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.67695259413233, 455.50122615735756, 480.91313631193515, 498.61413138134037, 499.2561083984862, 39573.60426395735, 42428.78785606068, 42429.36355954659, 42494.362584561255, 42580.14839703204, 42772.0, 42856.0, 43338.566614333846, 43460.78269608648, 43571.56428435715, 8442.957785211065, 8711.782205444882, 9136.680216192435, 9369.0, 9386.20217281532, 11293.0, 11607.883921160765, 11609.651710448657, 11614.709632259219, 11645.592404265852, 11387.54449822006, 11463.140264480156, 11463.140264480156, 11468.25456345339, 11488.770224595502, 8486.575671216415, 8706.782330441727, 11150.442477876073, 11404.714882127972, 11456.198066135416, 11110.666679999533, 11376.829347559793, 11390.316581005167, 11395.658130256106, 11405.714857128554, 41609.79195104035, 41975.580244197474, 41995.37006944898, 42001.36997945003, 42061.0, 4018.9349822297113, 4042.7776472294013, 4042.8584999524874, 4071.1043570414463, 4224.408582798411, 30775.922842700624, 31016.0, 31939.688142100753, 31944.680553194405, 32028.199295017577, 3089.629244490653, 3188.855412701743, 3225.3871764364694, 3228.4350238708134, 3315.5192497087787, 344547.0, 344918.0, 346564.0, 464518.0, 465768.0, 61375.0, 61843.6907815458, 64287.35712642907, 64453.67773161198, 64754.67622661951, 68499.65750171218, 68544.31455685479, 68602.0, 68826.65586672135, 68856.311436886, 16054.0, 16315.347386104422, 16330.0, 16352.182390880645, 16389.934654247434, 11288.887111128948, 11585.0, 11597.014253788522, 12418.937905310415, 12576.937115314548, 17803.73294400585, 18369.17338719734, 18391.72412413815, 18396.080195990147, 18409.0, 6051.394860513913, 6274.905876411857, 7187.31720486553, 7200.243974382719, 7271.89092163618, 845.6659619450282, 863.8963324401112, 880.8854848869606, 896.9237614802815, 898.793277546168, 29062.70937290642, 29983.0, 30263.44067212082, 30558.27717902058, 30890.918817841266, 994.2443742755557, 1043.634727845248, 1048.3500229857384, 1076.9192310576739, 1078.5308390850005, 15123.470678526199, 15510.689786204439, 15552.688946221238, 15635.921820390826, 15936.840631593768, 565658.0, 572132.0, 573107.0, 574034.0, 576013.0, 61640.38359616436, 61873.690631547455, 61874.0, 61875.6906215466, 61916.0, 1385.5635474825397, 1430.7281616493042, 1446.2985452055746, 1447.1317209674112, 1450.2531196433845, 28656.856715717124, 29190.124296271148, 29237.7076229235, 29277.85361073181, 30035.399292014474, 42778.786106069274, 53066.0, 53080.40758777243, 53229.467705324, 53256.73371633117, 491362.0, 495327.0, 496393.0, 497373.0, 498221.0, 26540.867295663396, 26772.59841102346, 26988.190354289403, 27086.458270834868, 27188.864055679594, 39020.30043855239, 45449.772751136035, 48684.513154868, 48699.5130048695, 49438.50561494339, 36331.844569008186, 37030.0, 37433.43849842309, 41180.38229426621, 41971.160576788905, 30356.54465183068, 30591.847040764653, 40135.0, 41162.38256426216, 41435.585644143175, 38126.809365954105, 38365.0, 38921.41617875677, 39257.803710981265, 39428.0, 26672.866170705907, 32107.39463026886, 33565.0, 34436.82781586076, 34897.0, 24433.0, 25178.874105629355, 25467.23744462462, 25520.872395637904, 25715.485690285714], "value": 20070.252905873673, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.0617911966244, 388.32237745134734, 391.10437099043173, 399.64232012348947, 423.4199147168378, 15109.24453777311, 15474.38102475901, 18714.53213669659, 20559.280425185123, 21128.788712112873, 20838.374848754524, 21153.57692846146, 21191.35006447776, 21434.9282535873, 21546.0, 5643.576731745115, 5765.481106700396, 5925.614835035731, 5982.730777115018, 5991.340952495222, 3842.4812650292133, 5966.701664916758, 6017.39826017397, 6018.0, 6020.698965051752, 4045.8786236412957, 5607.327120745516, 5825.650460972334, 5892.057270836667, 5947.078202878561, 5753.165790960329, 5931.31789844167, 5944.643321400687, 5948.702564871739, 5950.523958083343, 5823.767049318065, 5826.679532625704, 5829.7376618052285, 5841.211436456067, 5842.824715258549, 13567.253801040939, 13585.59243222705, 13676.111052781538, 13779.862201378059, 13792.103513271606, 2580.2001379572393, 2597.196298796698, 2607.647967524379, 2616.2543675052707, 2619.3975385661215, 10073.748156296078, 10193.08262256393, 10673.519691613817, 11874.762504749942, 16101.838981610268, 1482.303317440812, 1844.5466439360932, 1856.0441372693074, 1870.3360307090923, 1896.7344571759966, 115678.42160789142, 122274.0, 125948.0, 133275.00087498792, 135915.32042339724, 8176.3867709922, 9776.608935642495, 10068.0, 10158.441285729356, 10561.735956601147, 12576.37118144089, 12706.428210730539, 12717.473903131684, 12718.491260349483, 12731.93634031824, 7180.851063829762, 8010.279074883289, 8227.794305142419, 8257.504549727035, 8422.0, 8038.598070096472, 8196.34429245659, 8377.83244335122, 8571.271441927382, 8804.383693141546, 3396.949045764266, 4023.7183397162503, 4036.8788936331957, 4043.2317859606583, 4045.6763458923215, 1986.2352994097378, 2017.9697304540443, 2031.380428969149, 2032.5325175209784, 2034.1659919433257, 991.6033586565432, 1000.6397696829135, 1010.6260683546988, 1026.6919924022764, 1031.1029404418205, 20538.486537836376, 20943.8952805235, 21267.149314027574, 21269.46826329354, 21280.829554374657, 866.2420382165606, 941.8392054158171, 990.760880410278, 1042.9426381549088, 1055.456439933435, 9218.0, 9689.32174747775, 10240.0, 10286.228532860065, 10756.781057994936, 680119.0, 680128.0, 723445.0, 735365.0, 757001.0, 116429.0, 125382.0, 126157.0, 129576.3696086702, 150950.0, 989.5052473763119, 991.3754334769194, 1031.128696251666, 1032.0711359776262, 1034.7775228325795, 19496.927668978344, 19663.0, 19844.404667859988, 19868.50328741793, 19898.60202795965, 41054.0, 50664.240036400224, 56032.71983640056, 57583.0, 60041.39958600358, 78872.60563697145, 82403.0, 84079.0, 87959.0, 90383.0, 8160.632771525415, 8189.262966333059, 8313.459625124347, 8657.567121643766, 8674.653013879373, 12393.0, 13041.217526948414, 13266.46934122663, 13375.799363009757, 13540.661483462993, 10864.891351086388, 11234.898930433637, 11433.314001159957, 11530.365829879102, 11665.59170429031, 10701.625443109453, 10892.673219803419, 11002.119830413707, 11111.0, 11512.94243528777, 11110.833662110103, 11602.25966105091, 11646.70883227926, 11843.58547450835, 11982.400879955792, 8229.094799572162, 8407.747767566983, 8467.668048395077, 8623.267022303175, 8650.697225597074, 9592.599569696342, 9595.664151754656, 11083.889161108285, 11342.489587968395, 11571.958523732905], "value": 9486.092189509469, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.3014327764479, 1408.133997591481, 1429.3996521460986, 1435.7774544945537, 1436.8419473857873, 8009.0, 8409.453385529936, 8434.409591328595, 8443.66225350986, 8455.408121431503, 7442.590657513834, 7636.503627264224, 7643.044619422572, 7975.361971042303, 8224.753257402273, 2253.2001139595413, 2282.440802003503, 2303.53929214157, 2304.3087073877855, 2304.4008557774932, 2261.2989973108306, 2296.5062511560013, 2303.5968705476557, 2309.7343805462333, 2314.0628045641456, 2021.4643119573325, 2093.748750149984, 2264.728232612081, 2294.736105347881, 2295.1393227539675, 2211.0824008036648, 2284.12061356379, 2284.303287497304, 2294.7705229477087, 2297.322289924476, 2224.9555008899733, 2283.3720726800098, 2288.3706980580305, 2292.86242825431, 2294.346111358255, 8278.544680042596, 8356.456830306011, 8360.247577717973, 8366.247037766569, 8377.9946406431, 5012.373453318335, 5182.481751824825, 5182.533571978503, 5188.066148093341, 5192.1432963560965, 3295.96704032959, 3413.4709120086427, 3460.2560449503226, 3484.6341134181052, 3492.109512074405, 1110.5946329589722, 1199.3223828536936, 1212.969675758113, 1258.4399942025802, 1269.9776679772779, 10488.0, 10531.894681053169, 10561.68314950545, 10702.892971070267, 10713.982171693755, 4151.4395556599475, 4356.607905288539, 4429.478564541198, 4439.66702497314, 4469.8710902368475, 8637.913620863837, 8641.438306510183, 8644.394892357475, 8652.307815374756, 8654.524001179996, 5076.923846142311, 5082.161443361878, 5083.110455670243, 5091.287219789232, 5101.540861322499, 4655.0, 4690.85074156827, 4697.812087516461, 4729.125111854318, 4780.760961951889, 3138.3095718941795, 3590.8743193988607, 3612.3317186320614, 3644.161842776123, 3680.9631903681156, 1123.5449642894641, 1141.8743938166795, 1144.347721798567, 1144.5879483385975, 1148.5004023249912, 757.806759276381, 806.4999700185922, 882.5234373438271, 895.9865602015975, 927.8958039932459, 5199.766010529535, 5595.132754423071, 5675.233843431167, 5712.628679135926, 5952.404759524013, 782.7496692957296, 917.124146440148, 945.7115579748105, 977.7555611097225, 986.2061040862175, 3600.6039335673054, 3644.453332000221, 3663.2673465307043, 3718.8326525306425, 3732.8320225589914, 9714.854277185848, 10062.798744025078, 10086.193104551618, 10093.545790439448, 10104.797904041878, 9936.602535898628, 10022.3485473444, 10036.548355324028, 10047.24645651579, 10081.49592520371, 7629.2752188542, 7647.847043059108, 7661.6552255147535, 7702.306792388712, 7818.804529886685, 5349.277847490539, 5381.2197231402015, 5459.590530710292, 5484.424135465719, 5664.603477756517, 9053.366264361432, 9149.542522873962, 9190.862137067816, 9199.26405887514, 9220.077992200726, 8857.512836794038, 9372.34393592442, 9379.484128373006, 9398.859017114612, 9401.85897211556, 6518.380753828379, 6735.360140786617, 6754.898676519954, 6802.965985170043, 6821.3519715626935, 7447.962760186165, 7629.694812207672, 7821.569813660303, 7873.173316801883, 7929.0, 7099.929000709927, 7109.964450177716, 7141.714331426892, 7175.282471752783, 7285.490015698851, 6364.713587888464, 6923.584584924921, 7005.579665220103, 7033.29667033313, 7061.505694601328, 5315.468453154654, 6384.553081284265, 6384.553081284451, 6386.329435409401, 6390.968045159744, 4813.302071199727, 4881.707097574157, 4947.604191664586, 5018.573421259234, 5049.990001999541, 4868.659193856396, 4963.975180124076, 5054.039732450822, 5083.46623604531, 5166.819161329336], "value": 4338.073684790972, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.983150842458, 351.7432274439659, 352.73192373795905, 362.932857421377, 368.8045335971936, 5283.196098808851, 5325.893482130355, 5353.518183363496, 5360.973195134024, 5408.756605952732, 4381.496127945279, 4926.901461970759, 5084.542391184793, 5118.539331460168, 5239.711815850127, 4126.649234815044, 4134.235166494208, 4161.875143745678, 4173.979130104361, 4207.600277973587, 4086.570910054446, 4109.979450102745, 4130.524989626201, 4142.627163555295, 4151.045259590296, 3260.3805276997414, 3336.549565808621, 4123.330051329216, 4143.08852052548, 4147.958520414787, 3420.7776494527775, 3420.880269190566, 3453.2575496268173, 4129.752214867118, 4142.92284006159, 3370.2753907909673, 3644.1982763791925, 3660.9267814643827, 3663.249033948034, 3687.7049836013052, 4276.572342765729, 5073.043677086808, 5144.305518754958, 5154.226865970097, 5222.608304377149, 4092.4679791627195, 4231.968655045595, 4741.57325840676, 4821.566059054702, 4827.61379089672, 4927.802887884481, 5589.484731870633, 5593.356763972155, 5601.551875849922, 5626.971865140648, 4070.674346052309, 4078.612531809503, 4102.548719640837, 4103.569125241836, 4113.424120623115, 5282.596418629552, 5470.890582188374, 5783.942160578383, 5796.0440175510375, 5810.883782324415, 3449.3618680544178, 3668.5047518584647, 4123.958760412417, 4138.689598280141, 4140.834366625301, 3502.667246611568, 4186.5604111568455, 4198.790060496963, 4199.0, 4232.174725928415, 4006.1787333596544, 4210.8420184448905, 4399.868003959887, 4558.037275770709, 4622.375979242759, 4489.865304040884, 4639.234526303122, 4950.950490495121, 5616.775328986876, 5646.2942132233475, 1114.9721256968478, 1124.6344937895217, 1189.2102385909325, 1235.4440501774059, 1270.790319597256, 683.5522732610049, 698.972510409697, 811.338758911497, 811.6875003123722, 865.5715420866585, 4183.790810459464, 4358.738475691469, 4698.929516057262, 4928.950710492849, 4959.454460009396, 4875.074345633526, 5296.278709419454, 5363.345144153669, 5417.83746487606, 5506.284183056177, 4508.07584445188, 4616.399868017136, 4868.807142250172, 4918.901621967612, 5066.46802085783, 2544.5572250639225, 2579.761714377072, 2904.259413849497, 2905.288204389938, 2931.5162998105075, 4794.904101917942, 4866.829660961849, 4990.850274491771, 5638.23726374082, 5723.136882604225, 5250.107481728039, 5360.0, 5470.264867566217, 5622.85942851432, 5654.321481422256, 5636.859078522905, 5701.4298570142655, 5726.2555867737765, 5747.482726554546, 5765.769369225268, 4738.573528382462, 4990.201567749143, 5344.225087362389, 5453.863653408538, 5495.258140151188, 4771.284307353924, 5125.974370128126, 5326.760295786621, 5387.407385187548, 5478.506934375925, 5041.647084704182, 5210.887139055239, 5496.615236933376, 5578.386377498553, 5744.368119506935, 3278.737685990863, 3312.354090952241, 3474.6004209516227, 3497.632748561364, 3569.928601427905, 4637.536246375335, 5055.32382546136, 5230.503102205284, 5455.618106732491, 5473.8357849264585, 4809.278608208797, 5276.9472305278, 5305.946940530545, 5344.518993290622, 5358.517733404013, 4795.39354316308, 4915.655904086822, 5154.020736060136, 5189.506996835295, 5230.372355317386, 5154.175331946871, 5181.585473162213, 5257.9221259642445, 5258.211268309784, 5263.394709608444, 4629.768511574475, 5265.420803711665, 5342.652727572696, 5433.972830135824, 5509.47659972302, 4157.168566286694, 4354.825806967686, 4907.950920490892, 5092.5740792578135, 5569.718964638075], "value": 3970.557878989086, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.2457183099995, 721.2246834652748, 736.8689062289387, 786.1548835002374, 845.9027211870634, 15058.698826023472, 15932.761008584881, 15954.28205730742, 15955.122468264255, 15972.68054638908, 14752.778708319356, 14894.47869324574, 14914.850851491508, 14922.626934326647, 14933.402663893432, 678.6572780745742, 704.6265479295981, 709.9503034787567, 870.8171284030361, 881.0704706534603, 863.3999370437548, 882.1266945723719, 882.3691060891462, 886.5079880666227, 888.537960260664, 890.5769759364282, 891.4606662968907, 932.4358762948386, 937.2221056523103, 937.8921424036255, 717.8061923280685, 742.8662840688673, 885.8139790643972, 886.5965985476593, 886.7605746448487, 671.8186089755787, 693.9971740834496, 699.3041923286312, 700.0654126740807, 701.6702149989496, 15070.547883563511, 15439.845601543953, 15489.612759680986, 15491.302891369915, 15517.612059698486, 11606.30362178272, 13918.164910105426, 13965.441382344694, 14032.03169326014, 14219.289035548285, 12526.74946501074, 12795.744085118245, 12852.421641026176, 12975.416106275241, 13163.144395614254, 4478.350639157336, 4481.170983368054, 4529.909401811979, 4536.4102666653125, 4646.9767651161865, 101630.49184754102, 105548.0, 106157.46921265422, 108084.0, 108563.0, 11024.44877756109, 11140.275882067639, 11375.488103035217, 11389.601363952223, 11618.07055435563, 18802.81197188038, 18959.241630334633, 22918.854057297067, 22923.656145157835, 22928.541429171324, 7689.692412303445, 7989.800254993555, 7995.0, 8014.79963000918, 8040.954675892097, 4266.168097221074, 4284.978575107167, 4334.978325108417, 4401.449818772653, 4484.798184081724, 3405.540252065989, 3493.633168517269, 3502.404591219499, 3510.297940411877, 3542.0790594445116, 618.3136718242747, 620.6710443464971, 626.7931582577738, 635.1742734445203, 641.5990006246096, 568.4003376437815, 582.3390451837196, 610.9083637454329, 662.3178126529682, 671.9059331693567, 9866.351811245544, 10059.949700251453, 10162.339447935861, 10541.736456588647, 10567.894321056845, 554.9445055494499, 579.3135134865198, 594.3432507079701, 635.9887778432314, 704.5983789240084, 4423.933640995388, 4945.332380128709, 4956.281339205867, 4966.478519755446, 4996.375453068366, 77055.0, 103485.0, 104967.20992143496, 107030.0, 107276.46361768141, 89668.0, 93233.5338323304, 93488.66278343096, 94536.58195127078, 94687.0, 80640.59679701563, 81146.0, 82136.0, 82187.5890620543, 82296.9999999988, 9391.389559678599, 9615.855762163434, 9652.420854748738, 9661.71014869555, 9672.70981870545, 41375.37936930888, 49821.0, 49934.0, 50276.0, 50950.23574646309, 67872.3212767866, 69142.3085769136, 70296.6485167571, 71973.64013179901, 72180.27819721735, 9874.85187722199, 11467.31196128235, 11713.707157320794, 11789.41052947332, 12491.188072775241, 10927.945360273467, 11614.419279035845, 11701.2979221247, 11881.702957426134, 12189.268643881041, 10798.622048228275, 11954.462049207632, 12053.939730301292, 12115.939420302842, 12309.630711078682, 11719.104654817045, 12016.33910134951, 12110.878891210976, 12160.817587736014, 12272.079594030305, 11822.940885295518, 11887.76224475523, 11914.702132446759, 11940.701482463008, 12299.815502767644, 9965.750856228653, 9984.550695218877, 10096.798064038825, 10222.080012798586, 10557.472126393803, 8371.62327695243, 9836.5081745911, 9880.011998800062, 9886.209103271578, 9887.752806179904], "value": 7355.720476679031, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 111.0, 112.0, 112.0, 113.0, 111.0, 112.0, 113.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 109.0, 147.0, 111.0, 110.0, 113.0, 89.0, 137.0, 140.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 181.0, 184.0, 183.0, 182.0, 186.0, 177.0, 183.0, 182.0, 185.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 58.0, 89.0, 60.0, 58.0, 58.0, 59.0, 73.0, 59.0], "value": 64.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 85.0, 71.0, 68.0, 70.0, 84.0, 67.0, 70.0, 82.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 91.0, 135.0, 98.0, 86.0, 83.0, 87.0, 84.0, 86.0, 97.0], "value": 93.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 101.0, 135.0], "value": 98.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 80.0, 86.0, 72.0, 127.0, 140.0, 96.0, 85.0, 84.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 74.0, 65.0, 79.0, 73.0, 70.0, 72.0, 73.0, 71.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 87.0, 87.0, 87.0, 85.0, 87.0, 96.0, 105.0, 77.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 141.0, 138.0, 136.0, 166.0, 147.0, 153.0, 135.0, 178.0, 146.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 173.0, 172.0, 173.0, 172.0, 148.0, 204.0, 237.0, 190.0], "value": 181.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 85.0, 87.0, 86.0, 66.0, 69.0, 67.0, 62.0, 67.0, 66.0], "value": 72.6, "unit": "score"}]}]} 04:50:38 INFO - Return code: 0 04:50:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:50:38 ERROR - # TBPL SUCCESS # 04:50:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:38 INFO - 2017-02-01 04:50:38.908 screenresolution[1927:17196] starting screenresolution argv=screenresolution get 04:50:38 INFO - 2017-02-01 04:50:38.912 screenresolution[1927:17196] Display 0: 1600x1200x32@60 04:50:38 INFO - 2017-02-01 04:50:38.918 screenresolution[1928:17199] starting screenresolution argv=screenresolution list 04:50:38 INFO - Available Modes on Display 0 04:50:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:39 INFO - Intel Iris: 04:50:39 INFO - Chipset Model: Intel Iris 04:50:39 INFO - Type: GPU 04:50:39 INFO - Bus: Built-In 04:50:39 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:39 INFO - Vendor: Intel (0x8086) 04:50:39 INFO - Device ID: 0x0a2e 04:50:39 INFO - Revision ID: 0x0009 04:50:39 INFO - Displays: 04:50:39 INFO - Display: 04:50:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:39 INFO - Display Serial Number: 1600x1200 60 04:50:39 INFO - Main Display: Yes 04:50:39 INFO - Mirror: Off 04:50:39 INFO - Online: Yes 04:50:39 INFO - Rotation: Supported 04:50:39 INFO - Return code: 0 04:50:39 INFO - Running post-action listener: _resource_record_post_action 04:50:39 INFO - [mozharness: 2017-02-01 12:50:39.156616Z] Finished run-tests step (success) 04:50:39 INFO - Running post-run listener: _resource_record_post_run 04:50:39 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 561152; Write bytes: 553991168; Read time: 6; Write time: 1662 04:50:39 INFO - TinderboxPrint: CPU usage
53.6% 04:50:39 INFO - TinderboxPrint: I/O read bytes / time
561,152 / 6 04:50:39 INFO - TinderboxPrint: I/O write bytes / time
553,991,168 / 1,662 04:50:39 INFO - TinderboxPrint: CPU idle
2,232.3 (46.4%) 04:50:39 INFO - TinderboxPrint: CPU system
957.1 (19.9%) 04:50:39 INFO - TinderboxPrint: CPU user
1,620.3 (33.7%) 04:50:39 INFO - TinderboxPrint: Swap in / out
252,248,064 / 0 04:50:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253182464; Write bytes: 256807424; Read time: 18367; Write time: 520 04:50:39 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 471040; Write bytes: 295774720; Read time: 4; Write time: 1136 04:50:39 INFO - Running post-run listener: _upload_blobber_files 04:50:39 INFO - Blob upload gear active. 04:50:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:50:39 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 04:50:39 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:50:39 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:50:40 INFO - (blobuploader) - INFO - Open directory for files ... 04:50:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:50:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:50:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:40 INFO - (blobuploader) - INFO - Done attempting. 04:50:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:50:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:50:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:41 INFO - (blobuploader) - INFO - Done attempting. 04:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:50:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:42 INFO - (blobuploader) - INFO - Done attempting. 04:50:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:50:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:44 INFO - (blobuploader) - INFO - Done attempting. 04:50:44 INFO - (blobuploader) - INFO - Iteration through files over. 04:50:44 INFO - Return code: 0 04:50:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:50:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:50:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/741014a4008302b3934582dff5edda70b3feaeb68fd77946a6da5cb5dd3009550189453e5a2ea3173be69f07e39a9880ab68ccbbe3417bc8d6ea2dc792bc8992", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e142167fb876754cc07890a079687d11bb11fff735e855208f0323e3f50e1f5b0c5a1500ea1e220e09139fd663c84b85b3268f6fca4fb4960cd31d36203de76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08026abca9c6866a76fa6ea3c4ea10843f141e93473d6420d7f142f9a5969702a4bb60a936ec895ec7338e4d3b605850cd47f54835c17ddd464ee74f53a55743", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7494e97f6653039b9ba5781df641dd847666b7b3facf774b8576a66932c0a90fb20a02951efc0cc7339cbfb13febaa431f96433172a2a14443a35ffc33e60e1"} 04:50:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:44 INFO - Contents: 04:50:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/741014a4008302b3934582dff5edda70b3feaeb68fd77946a6da5cb5dd3009550189453e5a2ea3173be69f07e39a9880ab68ccbbe3417bc8d6ea2dc792bc8992", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e142167fb876754cc07890a079687d11bb11fff735e855208f0323e3f50e1f5b0c5a1500ea1e220e09139fd663c84b85b3268f6fca4fb4960cd31d36203de76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08026abca9c6866a76fa6ea3c4ea10843f141e93473d6420d7f142f9a5969702a4bb60a936ec895ec7338e4d3b605850cd47f54835c17ddd464ee74f53a55743", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7494e97f6653039b9ba5781df641dd847666b7b3facf774b8576a66932c0a90fb20a02951efc0cc7339cbfb13febaa431f96433172a2a14443a35ffc33e60e1"} 04:50:44 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:44 INFO - Copying logs to upload dir... 04:50:44 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.294092 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-01 04:50:44.990427) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 04:50:44.997655) ========= 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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/Firefox/sha512/741014a4008302b3934582dff5edda70b3feaeb68fd77946a6da5cb5dd3009550189453e5a2ea3173be69f07e39a9880ab68ccbbe3417bc8d6ea2dc792bc8992", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e142167fb876754cc07890a079687d11bb11fff735e855208f0323e3f50e1f5b0c5a1500ea1e220e09139fd663c84b85b3268f6fca4fb4960cd31d36203de76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08026abca9c6866a76fa6ea3c4ea10843f141e93473d6420d7f142f9a5969702a4bb60a936ec895ec7338e4d3b605850cd47f54835c17ddd464ee74f53a55743", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7494e97f6653039b9ba5781df641dd847666b7b3facf774b8576a66932c0a90fb20a02951efc0cc7339cbfb13febaa431f96433172a2a14443a35ffc33e60e1"} build_url:https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011042 build_url: 'https://queue.taskcluster.net/v1/task/I_b72HY2QvaD0AMhfA-3ag/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/741014a4008302b3934582dff5edda70b3feaeb68fd77946a6da5cb5dd3009550189453e5a2ea3173be69f07e39a9880ab68ccbbe3417bc8d6ea2dc792bc8992", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e142167fb876754cc07890a079687d11bb11fff735e855208f0323e3f50e1f5b0c5a1500ea1e220e09139fd663c84b85b3268f6fca4fb4960cd31d36203de76", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08026abca9c6866a76fa6ea3c4ea10843f141e93473d6420d7f142f9a5969702a4bb60a936ec895ec7338e4d3b605850cd47f54835c17ddd464ee74f53a55743", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e7494e97f6653039b9ba5781df641dd847666b7b3facf774b8576a66932c0a90fb20a02951efc0cc7339cbfb13febaa431f96433172a2a14443a35ffc33e60e1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 04:50:45.034687) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 04:50:45.034997) ========= 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.Erl7FcPxcP/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.DRQlQHU0YN/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005103 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 04:50:45.083835) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 04:50:45.084140) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 04:50:45.087455) ========= ========= Total master_lag: 1.85 =========