builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0284 starttime: 1493629741.15 results: success (0) buildid: 20170501004005 builduid: bcbe905d1ff84f8ea182293ad3573ff0 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.149424) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.156001) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.156292) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.235802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.236166) ========= 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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-01 02:09:01-- 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% 176M=0s 2017-05-01 02:09:01 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.188489 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.449478) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.449800) ========= 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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.033790 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.537796) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:01.538139) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-01 02:09:01,598 truncating revision to first 12 chars 2017-05-01 02:09:01,598 Setting DEBUG logging. 2017-05-01 02:09:01,598 attempt 1/10 2017-05-01 02:09:01,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-01 02:09:02,037 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.714215 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:02.278173) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:02.278633) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:02.279217) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:02.279609) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-01 02:09:02.301108) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-05-01 02:09:02.301499) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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 02:09:02 INFO - MultiFileLogger online at 20170501 02:09:02 in /builds/slave/test 02:09:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:09:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:09:02 INFO - {'append_to_log': False, 02:09:02 INFO - 'base_work_dir': '/builds/slave/test', 02:09:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:09:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:09:02 INFO - 'branch': 'Mozilla-Aurora', 02:09:02 INFO - 'buildbot_json_path': 'buildprops.json', 02:09:02 INFO - 'config_files': ('talos/mac_config.py',), 02:09:02 INFO - 'default_actions': ('clobber', 02:09:02 INFO - 'read-buildbot-config', 02:09:02 INFO - 'download-and-extract', 02:09:02 INFO - 'populate-webroot', 02:09:02 INFO - 'create-virtualenv', 02:09:02 INFO - 'install', 02:09:02 INFO - 'run-tests'), 02:09:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:09:02 INFO - 'download_minidump_stackwalk': True, 02:09:02 INFO - 'download_symbols': 'ondemand', 02:09:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:09:02 INFO - 'tooltool.py': '/tools/tooltool.py', 02:09:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:09:02 INFO - '/tools/misc-python/virtualenv.py')}, 02:09:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:09:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:09:02 INFO - 'gecko_profile': False, 02:09:02 INFO - 'gecko_profile_interval': 0, 02:09:02 INFO - 'installer_path': 'installer.exe', 02:09:02 INFO - 'log_level': 'info', 02:09:02 INFO - 'log_name': 'talos', 02:09:02 INFO - 'log_to_console': True, 02:09:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:09:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:09:02 INFO - 'opt_config_files': (), 02:09:02 INFO - 'pip_index': False, 02:09:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:09:02 INFO - 'cmd': ('bash', 02:09:02 INFO - '-c', 02:09:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:09:02 INFO - 'enabled': True, 02:09:02 INFO - 'halt_on_failure': False, 02:09:02 INFO - 'name': 'check_screen_resolution'},), 02:09:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:09:02 INFO - 'cmd': ('bash', 02:09:02 INFO - '-c', 02:09:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:09:02 INFO - 'enabled': True, 02:09:02 INFO - 'halt_on_failure': False, 02:09:02 INFO - 'name': 'check_screen_resolution'},), 02:09:02 INFO - 'run_cmd_checks_enabled': True, 02:09:02 INFO - 'suite': 'dromaeojs', 02:09:02 INFO - 'system_bits': '32', 02:09:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:09:02 INFO - 'title': 't-yosemite-r7-0284', 02:09:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:09:02 INFO - 'use_talos_json': True, 02:09:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:09:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:09:02 INFO - 'work_dir': 'build'} 02:09:02 INFO - [mozharness: 2017-05-01 09:09:02.468947Z] Running clobber step. 02:09:02 INFO - Running pre-action listener: _resource_record_pre_action 02:09:02 INFO - Running main action method: clobber 02:09:02 INFO - rmtree: /builds/slave/test/build 02:09:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:09:05 INFO - Running post-action listener: _resource_record_post_action 02:09:05 INFO - [mozharness: 2017-05-01 09:09:05.046456Z] Finished clobber step (success) 02:09:05 INFO - [mozharness: 2017-05-01 09:09:05.046582Z] Running read-buildbot-config step. 02:09:05 INFO - Running pre-action listener: _resource_record_pre_action 02:09:05 INFO - Running main action method: read_buildbot_config 02:09:05 INFO - Using buildbot properties: 02:09:05 INFO - { 02:09:05 INFO - "project": "", 02:09:05 INFO - "product": "firefox", 02:09:05 INFO - "script_repo_revision": "production", 02:09:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:09:05 INFO - "repository": "", 02:09:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:09:05 INFO - "buildid": "20170501004005", 02:09:05 INFO - "stage_platform": "macosx64", 02:09:05 INFO - "basedir": "/builds/slave/test", 02:09:05 INFO - "nightly_build": "True", 02:09:05 INFO - "buildnumber": 0, 02:09:05 INFO - "slavename": "t-yosemite-r7-0284", 02:09:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:09:05 INFO - "platform": "yosemite_r7", 02:09:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:09:05 INFO - "branch": "mozilla-aurora", 02:09:05 INFO - "repo_path": "releases/mozilla-aurora", 02:09:05 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 02:09:05 INFO - "pgo_build": "False", 02:09:05 INFO - "builduid": "bcbe905d1ff84f8ea182293ad3573ff0", 02:09:05 INFO - "slavebuilddir": "test" 02:09:05 INFO - } 02:09:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:09:05 INFO - Running post-action listener: _resource_record_post_action 02:09:05 INFO - [mozharness: 2017-05-01 09:09:05.049875Z] Finished read-buildbot-config step (success) 02:09:05 INFO - [mozharness: 2017-05-01 09:09:05.049967Z] Running download-and-extract step. 02:09:05 INFO - Running pre-action listener: _resource_record_pre_action 02:09:05 INFO - Running main action method: download_and_extract 02:09:05 INFO - mkdir: /builds/slave/test/build/tests 02:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:05 INFO - trying https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:09:05 INFO - Downloading https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:09:06 INFO - Downloaded 1199 bytes. 02:09:06 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:09:06 INFO - Using the following test package requirements: 02:09:06 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:09:06 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:09:06 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:09:06 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'jsshell-mac.zip'], 02:09:06 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:09:06 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:09:06 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:09:06 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:09:06 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:09:06 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:09:06 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:09:06 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:09:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:09:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:09:06 INFO - Fetch https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:09:08 INFO - Expected file size: 51794662 02:09:08 INFO - Obtained file size: 51794662 02:09:23 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:09:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:09:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:09:23 INFO - Fetch https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:09:25 INFO - Expected file size: 51794662 02:09:25 INFO - Obtained file size: 51794662 02:09:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:09:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:09:40 INFO - Fetch https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:09:42 INFO - Expected file size: 14404424 02:09:42 INFO - Obtained file size: 14404424 02:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:42 INFO - trying https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:09:42 INFO - Downloading https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:09:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:09:46 INFO - Downloaded 74783796 bytes. 02:09:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:09:46 INFO - mkdir: /builds/slave/test/properties 02:09:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:09:46 INFO - Writing to file /builds/slave/test/properties/build_url 02:09:46 INFO - Contents: 02:09:46 INFO - build_url:https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:09:46 INFO - Running post-action listener: _resource_record_post_action 02:09:46 INFO - Running post-action listener: set_extra_try_arguments 02:09:46 INFO - [mozharness: 2017-05-01 09:09:46.860143Z] Finished download-and-extract step (success) 02:09:46 INFO - [mozharness: 2017-05-01 09:09:46.860276Z] Running populate-webroot step. 02:09:46 INFO - Running pre-action listener: _resource_record_pre_action 02:09:46 INFO - Running main action method: populate_webroot 02:09:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:09:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:09:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:09:46 INFO - '--tppagecycles', 02:09:46 INFO - '7'], 02:09:46 INFO - 'tests': ['tsvgm']}}, 02:09:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:09:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:09:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:09:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:09:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:09:46 INFO - 'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:09:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:09:46 INFO - 'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['damp', 'tps']}, 02:09:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:09:46 INFO - 'tests': ['damp', 'tps']}, 02:09:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['dromaeo_dom']}, 02:09:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:09:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['basic_compositor_video']}, 02:09:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:09:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['a11yr', 02:09:46 INFO - 'ts_paint', 02:09:46 INFO - 'tpaint', 02:09:46 INFO - 'sessionrestore', 02:09:46 INFO - 'sessionrestore_no_auto_restore', 02:09:46 INFO - 'tabpaint']}, 02:09:46 INFO - 'other-e10s': {'tests': ['a11yr', 02:09:46 INFO - 'ts_paint', 02:09:46 INFO - 'tpaint', 02:09:46 INFO - 'sessionrestore', 02:09:46 INFO - 'sessionrestore_no_auto_restore', 02:09:46 INFO - 'tabpaint']}, 02:09:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:09:46 INFO - 'ts_paint', 02:09:46 INFO - 'tpaint', 02:09:46 INFO - 'sessionrestore', 02:09:46 INFO - 'sessionrestore_no_auto_restore', 02:09:46 INFO - 'tabpaint']}, 02:09:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:09:46 INFO - 'ts_paint', 02:09:46 INFO - 'tpaint', 02:09:46 INFO - 'sessionrestore', 02:09:46 INFO - 'sessionrestore_no_auto_restore', 02:09:46 INFO - 'tabpaint']}, 02:09:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['a11yr', 02:09:46 INFO - 'ts_paint', 02:09:46 INFO - 'tpaint', 02:09:46 INFO - 'sessionrestore', 02:09:46 INFO - 'sessionrestore_no_auto_restore', 02:09:46 INFO - 'tabpaint']}, 02:09:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['a11yr', 02:09:46 INFO - 'ts_paint', 02:09:46 INFO - 'tpaint', 02:09:46 INFO - 'sessionrestore', 02:09:46 INFO - 'sessionrestore_no_auto_restore', 02:09:46 INFO - 'tabpaint']}, 02:09:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['tsvgx', 02:09:46 INFO - 'tsvgr_opacity', 02:09:46 INFO - 'tart', 02:09:46 INFO - 'tscrollx', 02:09:46 INFO - 'cart', 02:09:46 INFO - 'tsvg_static']}, 02:09:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:09:46 INFO - 'tsvgr_opacity', 02:09:46 INFO - 'tart', 02:09:46 INFO - 'tscrollx', 02:09:46 INFO - 'cart', 02:09:46 INFO - 'tsvg_static']}, 02:09:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:09:46 INFO - 'talos_options': ['--disable-e10s'], 02:09:46 INFO - 'tests': ['tp5o']}, 02:09:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:09:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:09:46 INFO - 'talos_options': ['--disable-e10s', 02:09:46 INFO - '--xperf_path', 02:09:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:46 INFO - 'tests': ['tp5n']}, 02:09:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:09:46 INFO - 'talos_options': ['--xperf_path', 02:09:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:46 INFO - 'tests': ['tp5n']}}, 02:09:46 INFO - 'talos.zip': {'path': '', 02:09:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:09:46 INFO - Running post-action listener: _resource_record_post_action 02:09:46 INFO - [mozharness: 2017-05-01 09:09:46.878737Z] Finished populate-webroot step (success) 02:09:46 INFO - [mozharness: 2017-05-01 09:09:46.878859Z] Running create-virtualenv step. 02:09:46 INFO - Running pre-action listener: _resource_record_pre_action 02:09:46 INFO - Running main action method: create_virtualenv 02:09:46 INFO - Creating virtualenv /builds/slave/test/build/venv 02:09:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:09:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:09:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:09:47 INFO - Using real prefix '/tools/python27' 02:09:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:09:47 INFO - Installing distribute.............................................................................................................................................................................................done. 02:09:50 INFO - Installing pip.................done. 02:09:50 INFO - Return code: 0 02:09:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:09:50 INFO - Reading from file tmpfile_stdout 02:09:50 INFO - Output received: 02:09:50 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:09:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:50 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:50 INFO - 'HOME': '/Users/cltbld', 02:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:50 INFO - 'LOGNAME': 'cltbld', 02:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:50 INFO - 'MOZ_NO_REMOTE': '1', 02:09:50 INFO - 'NO_EM_RESTART': '1', 02:09:50 INFO - 'PAGER': '/bin/cat', 02:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:50 INFO - 'PWD': '/builds/slave/test', 02:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:50 INFO - 'SHELL': '/bin/bash', 02:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:09:50 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:50 INFO - 'USER': 'cltbld', 02:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:50 INFO - 'XPC_FLAGS': '0x0', 02:09:50 INFO - 'XPC_SERVICE_NAME': '0', 02:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:09:51 INFO - Cleaning up... 02:09:51 INFO - Return code: 0 02:09:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:51 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:51 INFO - 'HOME': '/Users/cltbld', 02:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:51 INFO - 'LOGNAME': 'cltbld', 02:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:51 INFO - 'MOZ_NO_REMOTE': '1', 02:09:51 INFO - 'NO_EM_RESTART': '1', 02:09:51 INFO - 'PAGER': '/bin/cat', 02:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:51 INFO - 'PWD': '/builds/slave/test', 02:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:51 INFO - 'SHELL': '/bin/bash', 02:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:09:51 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:51 INFO - 'USER': 'cltbld', 02:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:51 INFO - 'XPC_FLAGS': '0x0', 02:09:51 INFO - 'XPC_SERVICE_NAME': '0', 02:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:51 INFO - Downloading/unpacking psutil>=3.1.1 02:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:09:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:55 INFO - Installing collected packages: psutil 02:09:55 INFO - Running setup.py install for psutil 02:09:55 INFO - building 'psutil._psutil_osx' extension 02:09:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:09:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:09:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:09:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:09:56 INFO - building 'psutil._psutil_posix' extension 02:09:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:09:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:09:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:09:56 INFO - ^ 02:09:56 INFO - 1 warning generated. 02:09:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:09:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:56 INFO - Successfully installed psutil 02:09:56 INFO - Cleaning up... 02:09:56 INFO - Return code: 0 02:09:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:56 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:56 INFO - 'HOME': '/Users/cltbld', 02:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:56 INFO - 'LOGNAME': 'cltbld', 02:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:56 INFO - 'MOZ_NO_REMOTE': '1', 02:09:56 INFO - 'NO_EM_RESTART': '1', 02:09:56 INFO - 'PAGER': '/bin/cat', 02:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:56 INFO - 'PWD': '/builds/slave/test', 02:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:56 INFO - 'SHELL': '/bin/bash', 02:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:09:56 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:56 INFO - 'USER': 'cltbld', 02:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:56 INFO - 'XPC_FLAGS': '0x0', 02:09:56 INFO - 'XPC_SERVICE_NAME': '0', 02:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:09:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:09:59 INFO - Installing collected packages: mozsystemmonitor 02:09:59 INFO - Running setup.py install for mozsystemmonitor 02:09:59 INFO - Successfully installed mozsystemmonitor 02:09:59 INFO - Cleaning up... 02:09:59 INFO - Return code: 0 02:09:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:59 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:09:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:09:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:59 INFO - 'HOME': '/Users/cltbld', 02:09:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:59 INFO - 'LOGNAME': 'cltbld', 02:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:59 INFO - 'MOZ_NO_REMOTE': '1', 02:09:59 INFO - 'NO_EM_RESTART': '1', 02:09:59 INFO - 'PAGER': '/bin/cat', 02:09:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:59 INFO - 'PWD': '/builds/slave/test', 02:09:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:59 INFO - 'SHELL': '/bin/bash', 02:09:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:09:59 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:09:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:59 INFO - 'USER': 'cltbld', 02:09:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:59 INFO - 'XPC_FLAGS': '0x0', 02:09:59 INFO - 'XPC_SERVICE_NAME': '0', 02:09:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:00 INFO - Downloading/unpacking jsonschema==2.5.1 02:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:10:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:03 INFO - Downloading functools32-3.2.3-2.tar.gz 02:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:10:03 INFO - warning: no files found matching '*.txt' 02:10:03 INFO - no previously-included directories found matching 'build' 02:10:03 INFO - no previously-included directories found matching 'dist' 02:10:03 INFO - no previously-included directories found matching '.git*' 02:10:03 INFO - Installing collected packages: jsonschema, functools32 02:10:03 INFO - Running setup.py install for functools32 02:10:03 INFO - warning: no files found matching '*.txt' 02:10:03 INFO - no previously-included directories found matching 'build' 02:10:03 INFO - no previously-included directories found matching 'dist' 02:10:03 INFO - no previously-included directories found matching '.git*' 02:10:03 INFO - Successfully installed jsonschema functools32 02:10:03 INFO - Cleaning up... 02:10:03 INFO - Return code: 0 02:10:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:03 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:03 INFO - 'HOME': '/Users/cltbld', 02:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:03 INFO - 'LOGNAME': 'cltbld', 02:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:03 INFO - 'MOZ_NO_REMOTE': '1', 02:10:03 INFO - 'NO_EM_RESTART': '1', 02:10:03 INFO - 'PAGER': '/bin/cat', 02:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:03 INFO - 'PWD': '/builds/slave/test', 02:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:03 INFO - 'SHELL': '/bin/bash', 02:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:10:03 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:03 INFO - 'USER': 'cltbld', 02:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:03 INFO - 'XPC_FLAGS': '0x0', 02:10:03 INFO - 'XPC_SERVICE_NAME': '0', 02:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:10:03 INFO - Cleaning up... 02:10:03 INFO - Return code: 0 02:10:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:03 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:10:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:10:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:10:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:03 INFO - 'HOME': '/Users/cltbld', 02:10:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:03 INFO - 'LOGNAME': 'cltbld', 02:10:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:03 INFO - 'MOZ_NO_REMOTE': '1', 02:10:03 INFO - 'NO_EM_RESTART': '1', 02:10:03 INFO - 'PAGER': '/bin/cat', 02:10:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:03 INFO - 'PWD': '/builds/slave/test', 02:10:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:03 INFO - 'SHELL': '/bin/bash', 02:10:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:10:03 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:10:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:03 INFO - 'USER': 'cltbld', 02:10:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:03 INFO - 'XPC_FLAGS': '0x0', 02:10:03 INFO - 'XPC_SERVICE_NAME': '0', 02:10:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:04 INFO - Downloading/unpacking blobuploader==1.2.4 02:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:06 INFO - Downloading blobuploader-1.2.4.tar.gz 02:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:10:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:10:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:07 INFO - Downloading docopt-0.6.1.tar.gz 02:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:10:08 INFO - Installing collected packages: blobuploader, requests, docopt 02:10:08 INFO - Running setup.py install for blobuploader 02:10:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:10:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:10:08 INFO - Running setup.py install for requests 02:10:08 INFO - Running setup.py install for docopt 02:10:08 INFO - Successfully installed blobuploader requests docopt 02:10:08 INFO - Cleaning up... 02:10:08 INFO - Return code: 0 02:10:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10: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')]} 02:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:08 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:08 INFO - 'HOME': '/Users/cltbld', 02:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:08 INFO - 'LOGNAME': 'cltbld', 02:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:08 INFO - 'MOZ_NO_REMOTE': '1', 02:10:08 INFO - 'NO_EM_RESTART': '1', 02:10:08 INFO - 'PAGER': '/bin/cat', 02:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:08 INFO - 'PWD': '/builds/slave/test', 02:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:08 INFO - 'SHELL': '/bin/bash', 02:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:10:08 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:08 INFO - 'USER': 'cltbld', 02:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:08 INFO - 'XPC_FLAGS': '0x0', 02:10:08 INFO - 'XPC_SERVICE_NAME': '0', 02:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-R_W_zP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-FERGFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-1Cj5Qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-VarXI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-zSKFXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-wn6dWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-5l9IW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:10:09 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-9_Swav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-MNpj5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-3AoO_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-BuDMAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-p8QIeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-QFRl9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-BuM0Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-M9C2XE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-dVtarb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:10:10 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-yDyDdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:10:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:10:10 INFO - Running setup.py install for manifestparser 02:10:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:10:11 INFO - Running setup.py install for mozcrash 02:10:11 INFO - Running setup.py install for mozdebug 02:10:11 INFO - Running setup.py install for mozdevice 02:10:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:10:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:10:11 INFO - Running setup.py install for mozfile 02:10:11 INFO - Running setup.py install for mozhttpd 02:10:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:10:11 INFO - Running setup.py install for mozinfo 02:10:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:10:11 INFO - Running setup.py install for mozInstall 02:10:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for mozleak 02:10:12 INFO - Running setup.py install for mozlog 02:10:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for moznetwork 02:10:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for mozprocess 02:10:12 INFO - Running setup.py install for mozprofile 02:10:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:10:12 INFO - Running setup.py install for mozrunner 02:10:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Running setup.py install for mozscreenshot 02:10:13 INFO - Running setup.py install for moztest 02:10:13 INFO - Running setup.py install for mozversion 02:10:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:10:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:10:13 INFO - Cleaning up... 02:10:13 INFO - Return code: 0 02:10:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10: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')]} 02:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:13 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:10:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:13 INFO - 'HOME': '/Users/cltbld', 02:10:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:13 INFO - 'LOGNAME': 'cltbld', 02:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:13 INFO - 'MOZ_NO_REMOTE': '1', 02:10:13 INFO - 'NO_EM_RESTART': '1', 02:10:13 INFO - 'PAGER': '/bin/cat', 02:10:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:13 INFO - 'PWD': '/builds/slave/test', 02:10:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:13 INFO - 'SHELL': '/bin/bash', 02:10:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:10:13 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:10:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:13 INFO - 'USER': 'cltbld', 02:10:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:13 INFO - 'XPC_FLAGS': '0x0', 02:10:13 INFO - 'XPC_SERVICE_NAME': '0', 02:10:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:10:13 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-uxleTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:10:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:10:13 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Nr_1qF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-R2zS3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-i4SL32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-LNoHxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-mJFy9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-drx9Kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-p2Ejjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-bz79h4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-h49tvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-dP1CRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:10:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:10:14 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-1_UJKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:10:15 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-R6wZO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:10:15 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-F1_TBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:15 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-fMYlNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:10:15 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-SrE0nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:10:15 INFO - Running setup.py (path:/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/pip-Kzn0cE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - Downloading blessings-1.6.tar.gz 02:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:10:18 INFO - Installing collected packages: blessings 02:10:18 INFO - Running setup.py install for blessings 02:10:18 INFO - Successfully installed blessings 02:10:18 INFO - Cleaning up... 02:10:18 INFO - Return code: 0 02:10:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:10:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:18 INFO - Reading from file tmpfile_stdout 02:10:18 INFO - Current package versions: 02:10:18 INFO - blessings == 1.6 02:10:18 INFO - blobuploader == 1.2.4 02:10:18 INFO - docopt == 0.6.1 02:10:18 INFO - functools32 == 3.2.3-2 02:10:18 INFO - jsonschema == 2.5.1 02:10:18 INFO - manifestparser == 1.1 02:10:18 INFO - mozInstall == 1.12 02:10:18 INFO - mozcrash == 1.0 02:10:18 INFO - mozdebug == 0.1 02:10:18 INFO - mozdevice == 0.49 02:10:18 INFO - mozfile == 1.2 02:10:18 INFO - mozhttpd == 0.7 02:10:18 INFO - mozinfo == 0.9 02:10:18 INFO - mozleak == 0.1 02:10:18 INFO - mozlog == 3.4 02:10:18 INFO - moznetwork == 0.27 02:10:18 INFO - mozprocess == 0.25 02:10:18 INFO - mozprofile == 0.28 02:10:18 INFO - mozrunner == 6.13 02:10:18 INFO - mozscreenshot == 0.1 02:10:18 INFO - mozsystemmonitor == 0.3 02:10:18 INFO - moztest == 0.8 02:10:18 INFO - mozversion == 1.4 02:10:18 INFO - psutil == 3.1.1 02:10:18 INFO - requests == 1.2.3 02:10:18 INFO - wsgiref == 0.1.2 02:10:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:18 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 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:18 INFO - 'HOME': '/Users/cltbld', 02:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:18 INFO - 'LOGNAME': 'cltbld', 02:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:18 INFO - 'MOZ_NO_REMOTE': '1', 02:10:18 INFO - 'NO_EM_RESTART': '1', 02:10:18 INFO - 'PAGER': '/bin/cat', 02:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:18 INFO - 'PWD': '/builds/slave/test', 02:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:18 INFO - 'SHELL': '/bin/bash', 02:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:10:18 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:18 INFO - 'USER': 'cltbld', 02:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:18 INFO - 'XPC_FLAGS': '0x0', 02:10:18 INFO - 'XPC_SERVICE_NAME': '0', 02:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:10:19 INFO - Cleaning up... 02:10:19 INFO - Return code: 0 02:10:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab1db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab20768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1ba565650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, '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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:19 INFO - 'HOME': '/Users/cltbld', 02:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:19 INFO - 'LOGNAME': 'cltbld', 02:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:19 INFO - 'MOZ_NO_REMOTE': '1', 02:10:19 INFO - 'NO_EM_RESTART': '1', 02:10:19 INFO - 'PAGER': '/bin/cat', 02:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:19 INFO - 'PWD': '/builds/slave/test', 02:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:19 INFO - 'SHELL': '/bin/bash', 02:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:10:19 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:19 INFO - 'USER': 'cltbld', 02:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:19 INFO - 'XPC_FLAGS': '0x0', 02:10:19 INFO - 'XPC_SERVICE_NAME': '0', 02:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:10:19 INFO - Cleaning up... 02:10:19 INFO - Return code: 0 02:10:19 INFO - Running post-action listener: _resource_record_post_action 02:10:19 INFO - Running post-action listener: _start_resource_monitoring 02:10:19 INFO - Starting resource monitoring. 02:10:19 INFO - [mozharness: 2017-05-01 09:10:19.449323Z] Finished create-virtualenv step (success) 02:10:19 INFO - [mozharness: 2017-05-01 09:10:19.450291Z] Running install step. 02:10:19 INFO - Running pre-action listener: _resource_record_pre_action 02:10:19 INFO - Running main action method: install 02:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:19 INFO - Reading from file tmpfile_stdout 02:10:19 INFO - Detecting whether we're running mozinstall >=1.0... 02:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:10:19 INFO - Reading from file tmpfile_stdout 02:10:19 INFO - Output received: 02:10:19 INFO - Usage: mozinstall [options] installer 02:10:19 INFO - Options: 02:10:19 INFO - -h, --help show this help message and exit 02:10:19 INFO - -d DEST, --destination=DEST 02:10:19 INFO - Directory to install application into. [default: 02:10:19 INFO - "/builds/slave/test"] 02:10:19 INFO - --app=APP Application being installed. [default: firefox] 02:10:19 INFO - mkdir: /builds/slave/test/build/application 02:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:10:39 INFO - Reading from file tmpfile_stdout 02:10:39 INFO - Output received: 02:10:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:10:39 INFO - Running post-action listener: _resource_record_post_action 02:10:39 INFO - [mozharness: 2017-05-01 09:10:39.929319Z] Finished install step (success) 02:10:39 INFO - [mozharness: 2017-05-01 09:10:39.929459Z] Running run-tests step. 02:10:39 INFO - Running pre-action listener: _resource_record_pre_action 02:10:39 INFO - Running main action method: run_tests 02:10:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:10:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:10:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:10:39 INFO - 2017-05-01 02:10:39.943 screenresolution[1716:17536] starting screenresolution argv=screenresolution get 02:10:39 INFO - 2017-05-01 02:10:39.946 screenresolution[1716:17536] Display 0: 1600x1200x32@60 02:10:39 INFO - 2017-05-01 02:10:39.952 screenresolution[1717:17539] starting screenresolution argv=screenresolution list 02:10:39 INFO - Available Modes on Display 0 02:10:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:10:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:10:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:10:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:10:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:10:40 INFO - Intel Iris: 02:10:40 INFO - Chipset Model: Intel Iris 02:10:40 INFO - Type: GPU 02:10:40 INFO - Bus: Built-In 02:10:40 INFO - VRAM (Dynamic, Max): 1536 MB 02:10:40 INFO - Vendor: Intel (0x8086) 02:10:40 INFO - Device ID: 0x0a2e 02:10:40 INFO - Revision ID: 0x0009 02:10:40 INFO - Displays: 02:10:40 INFO - Display: 02:10:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:10:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:10:40 INFO - Display Serial Number: 1600x1200 60 02:10:40 INFO - Main Display: Yes 02:10:40 INFO - Mirror: Off 02:10:40 INFO - Online: Yes 02:10:40 INFO - Rotation: Supported 02:10:40 INFO - Return code: 0 02:10:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:10:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:10:40 INFO - Python 2.7.3 02:10:40 INFO - Return code: 0 02:10:40 INFO - grabbing minidump binary from tooltool 02:10:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:10:40 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 0x10a8fbd30>, '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 0x10a822db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:10:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:10:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:10:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:10:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:10:40 INFO - Return code: 0 02:10:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:10:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:10:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:10:40 INFO - ENV: RUST_BACKTRACE is now 1 02:10:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:10:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:10:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:10:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0284 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:10:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TTVKMCJN9Y/Render', 02:10:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:40 INFO - 'HOME': '/Users/cltbld', 02:10:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:40 INFO - 'LOGNAME': 'cltbld', 02:10:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:10:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:10:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:40 INFO - 'MOZ_NO_REMOTE': '1', 02:10:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:10:40 INFO - 'NO_EM_RESTART': '1', 02:10:40 INFO - 'PAGER': '/bin/cat', 02:10:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:40 INFO - 'PWD': '/builds/slave/test', 02:10:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:10:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:40 INFO - 'RUST_BACKTRACE': '1', 02:10:40 INFO - 'SHELL': '/bin/bash', 02:10:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7IjPU3DSkv/Listeners', 02:10:40 INFO - 'TMPDIR': '/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/', 02:10:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:40 INFO - 'USER': 'cltbld', 02:10:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:40 INFO - 'XPC_FLAGS': '0x0', 02:10:40 INFO - 'XPC_SERVICE_NAME': '0', 02:10:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0284', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:10:40 INFO - mozversion application_buildid: 20170501004005 02:10:40 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:10:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:10:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:10:40 INFO - mozversion application_name: Firefox 02:10:40 INFO - mozversion application_remotingname: firefox-dev 02:10:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:40 INFO - mozversion application_vendor: Mozilla 02:10:40 INFO - mozversion application_version: 54.0a2 02:10:40 INFO - mozversion platform_buildid: 20170501004005 02:10:40 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:10:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:40 INFO - mozversion platform_version: 54.0a2 02:10:40 INFO - using testdate: 1493629840 02:10:40 INFO - actual date: 1493629840 02:10:40 INFO - starting webserver on 'localhost:49241' 02:10:40 INFO - SUITE-START | Running 2 tests 02:10:40 INFO - TEST-START | dromaeo_css 02:10:40 INFO - operating with platform_type : mac_ 02:10:40 INFO - Initialising browser for dromaeo_css test... 02:10:40 INFO - TEST-INFO | started process 1727 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpVk4THR/profile http://localhost:49241/getInfo.html) 02:10:43 INFO - PROCESS | 1727 | __metrics Screen width/height:1600/1200 02:10:43 INFO - PROCESS | 1727 | colorDepth:24 02:10:43 INFO - PROCESS | 1727 | Browser inner width/height: 1024/689 02:10:43 INFO - PROCESS | 1727 | __metrics 02:10:43 INFO - PROCESS | 1727 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:10:43 INFO - PROCESS | 1727 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:10:43 INFO - TEST-INFO | 1727: exit 0 02:10:43 INFO - Browser initialized. 02:10:43 INFO - Running cycle 1/1 for dromaeo_css test... 02:10:43 INFO - TEST-INFO | started process 1731 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmpVk4THR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:10:56 INFO - PROCESS | 1731 | RSS: Main: 210714624 02:10:56 INFO - PROCESS | 1731 | 02:14:00 INFO - PROCESS | 1731 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 02:14:01 INFO - PROCESS | 1731 | RSS: Main: 355569664 02:14:01 INFO - PROCESS | 1731 | 02:15:45 INFO - PROCESS | 1731 | ************************* 02:15:45 INFO - PROCESS | 1731 | A coding exception was thrown and uncaught in a Task. 02:15:45 INFO - PROCESS | 1731 | 02:15:45 ERROR - PROCESS | 1731 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:15:45 INFO - PROCESS | 1731 | Full stack: 02:15:45 INFO - PROCESS | 1731 | ************************* 02:15:45 INFO - PROCESS | 1731 | ************************* 02:15:45 INFO - PROCESS | 1731 | A coding exception was thrown and uncaught in a Task. 02:15:45 INFO - PROCESS | 1731 | 02:15:45 ERROR - PROCESS | 1731 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:15:45 INFO - PROCESS | 1731 | Full stack: 02:15:45 INFO - PROCESS | 1731 | ************************* 02:15:45 INFO - PROCESS | 1731 | ************************* 02:15:45 INFO - PROCESS | 1731 | A coding exception was thrown and uncaught in a Task. 02:15:45 INFO - PROCESS | 1731 | 02:15:45 ERROR - PROCESS | 1731 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:15:45 INFO - PROCESS | 1731 | Full stack: 02:15:45 INFO - PROCESS | 1731 | ************************* 02:15:45 INFO - PROCESS | 1731 | ************************* 02:15:45 INFO - PROCESS | 1731 | A coding exception was thrown and uncaught in a Task. 02:15:45 INFO - PROCESS | 1731 | 02:15:45 ERROR - PROCESS | 1731 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:15:45 INFO - PROCESS | 1731 | Full stack: 02:15:45 INFO - PROCESS | 1731 | ************************* 02:17:04 INFO - PROCESS | 1731 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 02:17:05 INFO - PROCESS | 1731 | RSS: Main: 348762112 02:17:05 INFO - PROCESS | 1731 | 02:20:07 INFO - PROCESS | 1731 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 02:20:08 INFO - PROCESS | 1731 | RSS: Main: 323342336 02:20:08 INFO - PROCESS | 1731 | 02:23:10 INFO - PROCESS | 1731 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 02:23:11 INFO - PROCESS | 1731 | RSS: Main: 320638976 02:23:11 INFO - PROCESS | 1731 | 02:26:15 INFO - PROCESS | 1731 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 02:26:15 INFO - PROCESS | 1731 | RSS: Main: 323313664 02:26:15 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 02:29:19 INFO - PROCESS | 1731 | RSS: Main: 611483648 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | __start_tp_report 02:29:19 INFO - PROCESS | 1731 | _x_x_mozilla_page_load 02:29:19 INFO - PROCESS | 1731 | _x_x_mozilla_page_load_details 02:29:19 INFO - PROCESS | 1731 | |i|pagename|runs| 02:29:19 INFO - PROCESS | 1731 | |0;dojo.html;3397.8640854365826;3422.74329425293;3428.7771294865834;3449.189440481488;3486.0761898096994;5958.940410595893;5979.46184843364;5984.251968503937;5995.950708625989;6020.939790602092;5947.583669143161;6030.065339872316;6035.366286539905;6043.697815109238;6062.484688801457;5322.787088516455;5358.571314294866;5390.9191362129595;5444.265024221739;5480.725963701799;5273.630845840793;5281.102212623863;5324.094903866333;5461.399246082928;5478.233047373371;5372.113601255788;5388.353397592295;5417.26866872971;5432.2123292122615;5461.42655021223;5435.891282174334;5436.401995780461;5451.509364157205;5451.863703407407;5463.508284254416;5192.8961420771375;5322.893542129174;5381.946180538183;5392;5453.754581043862;4796.52034796521;5866.472017518444;5943.4056594340655;5997.460228579405;6026.397360263939;6344.111824344595;6369.235691716977;6373.6175829450385;6379.936200638027;6400.967995160041;6023.126646636257;6392.520560957901;6402.615843049385;6407.519436042269;6414.871702565969;5885.970570147122;5941.405859414111;5948;5964.1650168976375;6167.568270221086;6349.428551430394;6404.519661025442;6439;6447.677616119176;6514.771982980573;6366.363363663597;6377.489800815923;6381.680915954184;6382.840428989312;6388.393102655334;6418.550701450947;6427.164468619049;6429.3570642936265;6441.806745797634;6449.935500645028;6231.2522497300615;6250.468710159687;6264.780732674334;6358.1416508771645;6365.809025729235;6358.7456501740335;6386.680665966776;6399.552031357854;6401.935980640134;6411.51913606472;5103.642745007888;5138.743062846843;5139.743012849342;5152.819651312186;5167.121589329748;4486.663500237495;4490.057088011554;4573.153966516205;4604.769761511978;4650.302454631832;5805.332386775491;5808.564357673192;5812.88374232513;5817.650940943472;5820.126980952891;6292;6322.873542529123;6366.872662546816;6371.681415929185;6375.3624637536805;5893.410658934073;5936.28764548257;5950.732217050243;5966.254218222723;5969.611975221596;5632.239647647515;5632.3804381518;5636.605437619327;5640.238567793377;5651.8304450866535;6294;6361.332060133619;6441.967790161019;6541.967290163614;6572.507061970371;6369.7452101915405;6380.9361906380345;6383.585066970632;6385;6398.1362516059735;6542.640154791532;6554.606723596599;6558.1802274715665;6568.113304703804;6579.407853293035;6210.813675589739;6237.4698150657705;6321.399467050623;6355.618662880242;6385.7126429309865;6198.969005154945;6325.114483972339;6425.293217746138;6430.742770289136;6441.742330306735;6355.841103972438;6360.395762402564;6368.395002474757;6374;6375.234971803413;6047.697615119315;6111.755529778759;6337.144485494399;6356.586821856565;6368.808935731936;5120.513551212629;5993.460588547052;6119.228375855631;6255.499560035278;6262.091996660368;5480.068388374066;6341.270753863365;6353.174087368612;6360.745570177141;6363;5956.434138756811;6176.69116544162;6232.968835155976;6245.344238854855;6247.344028877084 02:29:19 INFO - PROCESS | 1731 | |1;ext.html;732.3152852083302;760.9239076092388;812.9715909374642;875.6366108065154;891.915268049535;27884.3028924277;40148.79925600372;40739.38890916635;40774.388384174184;40923.9769005775;42042;42330.153396932044;42345.67098809564;42365.57634423662;42403;7473.476856620039;9141.908580914172;9283.350165488418;9291.628334866597;9306.627734890597;7398.223186565414;10989.230753847234;11227.887721122766;11316.094712422986;11329.716757081056;11184.105271578253;11211.551537938514;11213.775724485506;11219;11248.38133902635;11126.554937802519;11164.218504704673;11173.938475844781;11178.944105279503;11185;10915.126789856871;10951.945240273828;10976.615818446397;10992.450377481175;10995.835062474069;39130.82607521779;39237.80381098076;39383.60616393828;39400.408993865116;39453.60546394557;3832.8689697108616;3846.134619710571;3853.7880416577077;3896.473976013259;3917.6474117329576;25850.74149258521;27045.86477067602;27836.721632783614;28959.276018099506;29171;3074.846257687129;3148.7323577495713;3194.632617249;3201.08769000836;3303.8513266903046;354413;365468;365497;365906.9999999973;366117.1694141539;51429.742851285504;51469.227961580604;51570.74214628903;51628.22557661638;51783.74108129435;54391.728041359536;54501;54522;54552;54591.72704136533;14515.201663908587;14612.196329201783;14633.268336583129;14640.975131740888;14648.780268295985;11080.667579972614;12752.426140823685;12760.979121670243;12767.297798620979;12773.680657983625;10181.643642472478;10229.539670714836;14003.229822359664;14144.656257655506;14377.353019114165;5329.8934021319355;5988.640681559121;6000.309964354155;6003.699815009232;6021.42796434338;1111.777644471109;1137.4653912661036;1151.3955173533961;1153.3368313219933;1192.9642110736693;24810.255692329258;25807.354816129748;26184.73815261861;27426.451470970467;27910.441791164063;869.9217070463689;955.245356168633;1041.6822869024868;1121.5513794482108;1285.517930775959;13627.182369057888;13790.448382064806;13992.300384980914;14037;14379.065360751518;506156.8768624681;508893;509767;510343;512711;46607.766961164976;48053;56542.86914261774;57027.42972570221;57825.554361140465;1415.2074838090703;1442.834074081473;1446.8119144510936;1456.6649670575828;1457.8979471437322;28868;29305.56041659334;29653.5551966725;30336.39327213399;30363.544546831374;46299;46380.840478986946;46393.76803116098;46463;46466.30300545562;469798;469827;470962;471116;476208;29701.40597188001;30208.84895575596;31055.68944310528;31137.688623114384;32528;37479.063023424635;38353.616463835;38640.806795965844;38811.6118838808;39086.804565976985;27189.320266993484;27530.862345688944;28381.006664766635;28467.28831779222;28809.70356333928;25981.936047059735;27231.183064508095;27635.585466218425;28177.15468535865;28532.286692832844;31525;32216.516752249205;32631.510527341634;32802.67197327996;32810.17974550655;27413;27432;27436;27507.724922751317;28629;20634.484137896074;20775.480612984193;21429.678554822003;22568.984395702562;22674.979625916545 02:29:19 INFO - PROCESS | 1731 | |2;jquery.html;390.0132664359172;400.8817398867334;412.66914201699524;430.4382780471485;435.36436802268685;12938.547150849723;16759.581010474743;19657.705134422977;20471.590568188665;21031.47421314468;23265.883670581665;23739.762602373972;24531.63202551963;24537;24825.50348993019;5336.439673834249;6224.5642805003445;6815.556988795737;7055;7123.750668726596;7060.82347941303;7073.080499535054;7141.500094993353;7149.213586505481;7152.964235178817;6858.862822743542;6932;6995.125609298838;7035.36681698647;7061.293870612949;6901;7003.5097543172;7068.36384725372;7076.6815493302665;7423.962880185564;7490.6629201686055;7560.319571238615;7745.728899488491;7761.22387761225;7773.494722842997;27589.724102758915;28184.718152818416;28280.29299267535;30137;30335.393292134035;3425.092350527112;3430.7426942979127;3509.91225219369;3547.7161827053774;3595.53258076451;16264.186790660538;17278.65442691152;17955;18030.819691803044;18373.632527349513;2026.23769020834;2038.6489732315686;2045.1103769860158;2109.7025329422295;2143.6248656485054;127436.72563274342;165307;169477.30522694738;169735;170926.14536927236;18625.81374186268;24797.87601061983;24800.875995619906;24842.50314993726;25466.74533254681;43712.78143609261;43715.78142109269;44322;45055.54944450514;45409.091818163455;11713;11740.647780566596;11805.822912656316;11833.349165795791;12064.758704825854;9354.953225233967;9368.67209647659;9385.15533601979;9523.619055237852;9575.616975321049;11546.960773530423;11650.6504804856;11655.067594592412;11717.828217178385;11770.705732356588;4503.041177087977;4566.3150527420485;4576.748278844629;4603.516630753723;4603.8849028773875;1014.3609525998721;1036.1400037968467;1054.3884546962743;1055.7255113670346;1066.0671912076932;20535.58928821445;21783.891080544498;21812.89093554554;21866.781332186474;21918.23286184976;1052.315994603514;1058.2433560004633;1058.9894101058892;1088.6353071721062;1103.8509801176738;11101.056410205061;11380.487878045507;11422.771544569063;11445.313281202987;11463.082953363712;584352;694523;733791;737304;752344.6679366196;159704;159951;165126;165911;171327;1179.9941000294943;1201.8677945425995;1250.5998080614115;1256.7360854220533;1257.4278703190027;19051.61896762085;19280.517987050436;19966.800331996496;19985.700214497087;20063.197472101536;66100.6694966522;66345.33654663392;66378.33621663721;66672.6666366665;67810.3218967804;90347.54826225826;90505.64241536515;90974.5451272766;92549;92976.53511732668;11178.99389054989;11350.94324528372;11554.76890462203;11726.530938762355;11864.584739534075;10931.906809319005;11952.940235299116;12481.56314528987;12654;12821.615351539469;10682.145428365868;10872.184586156229;11117.444127793417;11384.37385943781;11450.541978321107;10359.533820977924;10379.481025948522;10571.2600117991;10675.199360047873;10720.785584288427;9536.713898583053;9619.807603848023;10745.140388769036;10777;10860.565577377132;10186.643467478603;11114.944425277821;11303.660890173307;11404.94297528507;11464.25482343645;10513.947430263106;10584.417857017937;10682.305650132717;10917.563297468012;10953.123750100132 02:29:19 INFO - PROCESS | 1731 | |3;mootools.html;1315.1911574381754;1493.1936754152757;1551.7439622462293;1554.4870192836365;1562.570293169378;10434.478276086194;11065.004149626531;11076.556937722491;11090.500927458264;11104.722381940435;10644.467776611176;11050.005499505043;11204.66386008421;11210.383428911407;11210.439478026086;2007.2874129683971;2382.8451150675237;2390.282915125464;2390.378501589582;2391.485830546432;2296.712910886139;2373.9406514837096;2374.631932050535;2380.8690522021284;2385.6063749481314;2309.203324852923;2342.8594284342994;2348.178137651825;2375.2280508834697;2382.3091303522046;2301.5396920615776;2317.7913987741185;2322.245270287155;2332.8600283982846;2339.6841426407386;2072.4818795301176;2212.048819007826;2327.325075728037;2338.988305058481;2345.4253707841517;10950.726231844188;11032.062274706659;11039.558417663284;11040.944795276053;11057.391843448608;5468.835934921959;5653.152027195953;5653.406392328788;5663.546916246691;5670.6030577859565;3138.9686103139134;3409.1988382730037;3505.6494350564994;3581.3732596795717;3619.27614477103;1115.8437818705306;1144.4764840409698;1172.2731906218198;1176.2295696318924;1192.648168790209;15644.687106257925;15665.765013524806;15720.842791572053;15758.68482630341;15775.368985240577;3786.753860999026;3864.821229525022;3928.9017774555864;4548.681592288508;4568.040711450566;11536.596219132458;11561.364124973039;11564.13269004828;11564.190506664621;11584.594539191256;5538.501534861882;5549.19536667181;5556.111022236423;5558.888822223533;5569.248151499577;5018.397792264952;5022.070916880389;5051.1413059779925;5059.696418214919;5124.205748109049;3213.9839300803346;3710.6289371063217;3769.9434508482395;3792.3742582474133;3828.5979972102527;882.342654722236;944.3625552751865;982.1062832822098;988.2588058955783;989.8762654668167;639.8336432527483;736.3667246168291;756.606564586423;776.5301992294693;804.8913396691489;5048.899022019539;6310.242770867525;6531.248906375828;6554.115194448688;6602.108715323466;897.8428774964356;946.8674385586023;949.9572519236651;960.4093482508207;989.9407633831731;3645.76302540334;3761.586225515191;4067.5525692173837;4077.4903137107863;4153.314703074019;13376;13827;13834.169949802843;13837.86162138386;13856;11619.6514104577;12053.433053703138;13682.31588420575;13741;13763.518276860263;8752.081031491729;8948.642054317754;10162.0346067125;10204.642837500653;10405.06354428105;6833.145856767904;6854.520183587101;6894.827629309308;6944.305569443015;7043.154821421463;12127.332996685267;12364.567240146911;12544.184627999151;12547.498100075894;12579;12452.750944981231;12464.875351246372;12638.810417843555;12727.172733772275;12795.488180472677;9312.394694344846;9348.672796452092;9383.718488445358;9524;9547.952260238655;10282.434466104436;10363.740906477398;10439.94780026095;10481.318714283547;10560.683179504627;8918.554072296229;8945.910540894507;8973.102689731235;8975.596098175467;9038.683646072355;8656.78358041054;8742.475451472932;8858.822823543363;8874.29005679557;8912.55437228123;6394.360563943568;6753.2909044549615;7846.411519135973;7975.521468711896;8061.717839875811;5816.156657284755;5865.032269675456;5869.413058694096;6020.759169633166;6107.969460152671;6003.8198854034445;6014.789482368096;6064.241969753781;6076.726547305293;6078.696065196634 02:29:19 INFO - PROCESS | 1731 | |4;prototype.html;367.71134659292454;376.33201068104114;379.44790330069753;383;386.98452061917504;4952.597989974859;4972.701637901729;4992.950070499303;5073.7209453480045;5078.9746051269785;4694.577488026077;4768.73771942543;4809.791432496566;4828.56542911138;4953.73679711674;3540.4335306350927;4019.77891215983;4175.227582897159;4189.863169783402;4210.873673789792;3271.4275001874576;3478.8782392616263;3841.635044670752;3946.2699400610836;4207.15856828634;3523.8766643167496;3807.9809600951958;4016.919661606766;4192;4220.396249425214;3504.8072356020407;4042.312806822847;4188.308929026707;4232.619064284198;4239.851605193819;3565.3938830398893;3701.6298370163036;3759.2481503699096;3761.322961866863;3827.789471579062;3923.763454830748;4476.865694029152;4720;4753.453352864431;4826.710397376133;4252.872413827559;4475.361045083186;4781.6891902026255;4844.951550484485;4850.975745121287;4306.6124048835445;5048.873778155576;5202.453742357074;5211.739413029333;5331.5734741220995;3374.3588718143474;3731.5522137343423;3997.400389941503;4264.786760661955;4335.095486024371;4390.692651514363;4490;4852.902941941176;5434.701091439969;5731;4093.692973027035;4190.706650534495;4192.673066448089;4254.446921900133;4277.593628605277;4091.1204091120553;4386.929149595232;4440.378347031418;4449.199144154083;4452.866414007585;3692.9261414771554;3707.5736290326417;4326.113146804897;4697.003773396293;4699.882502937454;4734.502877197845;4788.880277993008;4871.7089971157675;5200.557952574073;5354.063038968243;1311.2919023727081;1509.9849001510065;1515.9393624255126;1524.9794022919675;1535.5470136309816;1018.332991890313;1021.406605695118;1050.4590136079926;1128.5824245029391;1131.7566723154562;4301.505326887385;4889.682170658896;4893.898872753602;4944.332515110414;4998.500449865099;4515.848458643025;4912.862904636495;5521.309836270466;5538.759825502046;5559.193916882029;4311.913761724811;4600.815967361335;4646.767661616906;4733.881652958704;4956.578690811321;2401.711794584626;2549.388146844744;2625.1492697648114;2794.4550812591756;2846.985765071203;4865.978144589605;5144.125498665236;5155.896882062338;5603.971980140073;5647.237622920935;4718.0328032753205;4776.23580227162;5096.770645320969;5583.274174357308;5655.211008624232;4894.037356321814;5538.363088244822;5551.583631227674;5614.017546929271;5666.356756540722;4944.406671199479;5151.562117220078;5345.33183352081;5447.330807904019;5478.780848766164;4722.008378240608;4742.170120229015;4837.911469919381;5337.172738225581;5367.490088441592;4948.826791062295;5619.1508816460855;5645.379008309001;5657.896710141483;5718.885622287614;4330.52364239927;4409;4413.22768515515;4416.801243943966;4435.334699795057;4926.137925862888;5547.55619550443;5579.74891129892;5618.6628802272;5627.380988091389;4125.050588829002;4741.312509686145;4973.73169841688;5310.840674779763;5373.205932914941;4952.331435256195;5332.626716129834;5344.866378340417;5394.087192915938;5404.297441332602;4692.319613656069;4873.610111191168;5175.92236116451;5224.190250511177;5231.77649498969;4462.754548499864;5014.373203349582;5272.446393128666;5276.459717296746;5368.221607866915;4260.382244574581;5161.432242453252;5207.791688332424;5209.505097015777;5252.581802913269 02:29:19 INFO - PROCESS | 1731 | |5;yui.html;718.9820254493641;738.2801768275929;774.0672489649972;826.9379796515261;840.7855996720837;15177.2411379431;15964.600884977881;16007.759883601726;16020.198990050494;16026.67946641068;11053.502592383342;14676.559703208894;14694.265286735674;14802.333894974725;14908.7018259635;934.6261495401843;935.177044201102;955.7323949294208;959.4722902403685;961.3126614470652;932.9720108396759;950.491487054426;952.4951775558931;952.5761036338832;955.2930831184942;1005.1205195453978;1008.6570566007556;1010.8635334229895;1014.3406785589352;1026.343140390151;1003.5433877585681;1004.1665417703289;1004.5278719002057;1005.271178395661;1006.8489726541004;750.1373420566338;751.443931490693;751.6392131776723;753.2091304130641;753.8153152477678;15533.533993980085;15651.530454086396;15849.366025358971;15945;15971.680566388608;13869.653258668513;14008.369623366973;14025.78961315581;14141.434342626282;14237.572872813833;10622.62820801276;11249.718757031058;11790;11807.350595717233;12079.09406794494;4389.209942210401;4430.889227769299;4454.37638730578;4517.751523666197;4573.131105090056;106551;106851;111569.88430115677;111824.4408777959;112517;10523.94738026305;10617.081938762862;10718.24972251935;10921;11192.664220073411;20689.862057586677;21339.573208536054;21362.893185533972;21366;21368.465788355414;7486.288802563748;7491.887621685679;7611;7625.466217364842;7786.065672119382;4184.497860256789;4260.659147268211;4336.176126536011;4354.281543545343;4425.291953287458;3358.9832050840077;3433.261848702541;3435.690787829107;3436.1237884339835;3454.5681789776277;541.6641682157087;615.4122812713847;625.2309659119218;631.5768435148461;651.1209866680039;551.1072063257549;552.1855263486326;561.3039830610014;573.8106424879779;574.6523353371234;9485.715428537154;9948.801023979624;10011.949940250252;10090.949545252226;10118.494075296207;569.8432930943941;594.7591225553658;596.6092209602717;601.3685630088389;601.4436646102338;4170.77060761655;4470.575295346936;4773.474917759044;4837.9032419351415;4895.796573439451;87572.5621371889;90489;90764.63853042209;93149;97090.51454742822;47441.48569542416;66367.66816165888;72173.63913180401;84878;88633.55683221671;73315.63342183361;74620;74726.25273747194;76138.23861761422;77013;10118.747031323981;11022.228444008842;11116.989947190395;11256.324620522795;11294.265872718248;40789;41561.75314740562;42088;42398.788006061004;42538.36192457178;58231;58335.12497312459;58360;58424;58550.41449585449;11973.76052478963;12290.81563776562;12337.568185113478;12636.43136058898;12646.683832904251;11751.941240293743;14884.404623814926;15150.621234468785;15346.232688365313;15349.769753453484;12846.697925773062;13162.210267383987;13929.303534823015;13934.790978135134;13958.162510249418;12570.219487118255;13486.12340197884;13655.317234138452;13764;13769;12770.169938953939;13036.674083147996;13058.934705326412;13651.317434128452;13921.721565568834;9242.815143696955;9665.661701840401;9833.803323933338;9839.508024598885;9895.406275623485;8705.521196334263;9342.579583918876;10455.738606534593;10529.210309226992;11414.543418263509 02:29:19 INFO - PROCESS | 1731 | __end_tp_report 02:29:19 INFO - PROCESS | 1731 | __start_cc_report 02:29:19 INFO - PROCESS | 1731 | _x_x_mozilla_cycle_collect,1515 02:29:19 INFO - PROCESS | 1731 | __end_cc_report 02:29:19 INFO - PROCESS | 1731 | __startTimestamp1493630959389__endTimestamp 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | ------- Summary: start ------- 02:29:19 INFO - PROCESS | 1731 | Number of tests: 6 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | [#0] dojo.html Cycles:165 Average:5914.22 Median:6167.57 stddev:656.35 (10.6%) stddev-sans-first:627.98 02:29:19 INFO - PROCESS | 1731 | Values: 3397.9 3422.7 3428.8 3449.2 3486.1 5958.9 5979.5 5984.3 5996.0 6020.9 5947.6 6030.1 6035.4 6043.7 6062.5 5322.8 5358.6 5390.9 5444.3 5480.7 5273.6 5281.1 5324.1 5461.4 5478.2 5372.1 5388.4 5417.3 5432.2 5461.4 5435.9 5436.4 5451.5 5451.9 5463.5 5192.9 5322.9 5381.9 5392.0 5453.8 4796.5 5866.5 5943.4 5997.5 6026.4 6344.1 6369.2 6373.6 6379.9 6401.0 6023.1 6392.5 6402.6 6407.5 6414.9 5886.0 5941.4 5948.0 5964.2 6167.6 6349.4 6404.5 6439.0 6447.7 6514.8 6366.4 6377.5 6381.7 6382.8 6388.4 6418.6 6427.2 6429.4 6441.8 6449.9 6231.3 6250.5 6264.8 6358.1 6365.8 6358.7 6386.7 6399.6 6401.9 6411.5 5103.6 5138.7 5139.7 5152.8 5167.1 4486.7 4490.1 4573.2 4604.8 4650.3 5805.3 5808.6 5812.9 5817.7 5820.1 6292.0 6322.9 6366.9 6371.7 6375.4 5893.4 5936.3 5950.7 5966.3 5969.6 5632.2 5632.4 5636.6 5640.2 5651.8 6294.0 6361.3 6442.0 6542.0 6572.5 6369.7 6380.9 6383.6 6385.0 6398.1 6542.6 6554.6 6558.2 6568.1 6579.4 6210.8 6237.5 6321.4 6355.6 6385.7 6199.0 6325.1 6425.3 6430.7 6441.7 6355.8 6360.4 6368.4 6374.0 6375.2 6047.7 6111.8 6337.1 6356.6 6368.8 5120.5 5993.5 6119.2 6255.5 6262.1 5480.1 6341.3 6353.2 6360.7 6363.0 5956.4 6176.7 6233.0 6245.3 6247.3 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | [#1] ext.html Cycles:180 Average:57897.64 Median:17065.27 stddev:120399.25 (705.5%) stddev-sans-first:120660.50 02:29:19 INFO - PROCESS | 1731 | Values: 732.3 760.9 813.0 875.6 891.9 27884.3 40148.8 40739.4 40774.4 40924.0 42042.0 42330.2 42345.7 42365.6 42403.0 7473.5 9141.9 9283.4 9291.6 9306.6 7398.2 10989.2 11227.9 11316.1 11329.7 11184.1 11211.6 11213.8 11219.0 11248.4 11126.6 11164.2 11173.9 11178.9 11185.0 10915.1 10951.9 10976.6 10992.5 10995.8 39130.8 39237.8 39383.6 39400.4 39453.6 3832.9 3846.1 3853.8 3896.5 3917.6 25850.7 27045.9 27836.7 28959.3 29171.0 3074.8 3148.7 3194.6 3201.1 3303.9 354413.0 365468.0 365497.0 365907.0 366117.2 51429.7 51469.2 51570.7 51628.2 51783.7 54391.7 54501.0 54522.0 54552.0 54591.7 14515.2 14612.2 14633.3 14641.0 14648.8 11080.7 12752.4 12761.0 12767.3 12773.7 10181.6 10229.5 14003.2 14144.7 14377.4 5329.9 5988.6 6000.3 6003.7 6021.4 1111.8 1137.5 1151.4 1153.3 1193.0 24810.3 25807.4 26184.7 27426.5 27910.4 869.9 955.2 1041.7 1121.6 1285.5 13627.2 13790.4 13992.3 14037.0 14379.1 506156.9 508893.0 509767.0 510343.0 512711.0 46607.8 48053.0 56542.9 57027.4 57825.6 1415.2 1442.8 1446.8 1456.7 1457.9 28868.0 29305.6 29653.6 30336.4 30363.5 46299.0 46380.8 46393.8 46463.0 46466.3 469798.0 469827.0 470962.0 471116.0 476208.0 29701.4 30208.8 31055.7 31137.7 32528.0 37479.1 38353.6 38640.8 38811.6 39086.8 27189.3 27530.9 28381.0 28467.3 28809.7 25981.9 27231.2 27635.6 28177.2 28532.3 31525.0 32216.5 32631.5 32802.7 32810.2 27413.0 27432.0 27436.0 27507.7 28629.0 20634.5 20775.5 21429.7 22569.0 22675.0 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | [#2] jquery.html Cycles:180 Average:43135.25 Median:20503.59 stddev:118231.72 (576.6%) stddev-sans-first:118519.83 02:29:19 INFO - PROCESS | 1731 | Values: 390.0 400.9 412.7 430.4 435.4 12938.5 16759.6 19657.7 20471.6 21031.5 23265.9 23739.8 24531.6 24537.0 24825.5 5336.4 6224.6 6815.6 7055.0 7123.8 7060.8 7073.1 7141.5 7149.2 7153.0 6858.9 6932.0 6995.1 7035.4 7061.3 6901.0 7003.5 7068.4 7076.7 7424.0 7490.7 7560.3 7745.7 7761.2 7773.5 27589.7 28184.7 28280.3 30137.0 30335.4 3425.1 3430.7 3509.9 3547.7 3595.5 16264.2 17278.7 17955.0 18030.8 18373.6 2026.2 2038.6 2045.1 2109.7 2143.6 127436.7 165307.0 169477.3 169735.0 170926.1 18625.8 24797.9 24800.9 24842.5 25466.7 43712.8 43715.8 44322.0 45055.5 45409.1 11713.0 11740.6 11805.8 11833.3 12064.8 9355.0 9368.7 9385.2 9523.6 9575.6 11547.0 11650.7 11655.1 11717.8 11770.7 4503.0 4566.3 4576.7 4603.5 4603.9 1014.4 1036.1 1054.4 1055.7 1066.1 20535.6 21783.9 21812.9 21866.8 21918.2 1052.3 1058.2 1059.0 1088.6 1103.9 11101.1 11380.5 11422.8 11445.3 11463.1 584352.0 694523.0 733791.0 737304.0 752344.7 159704.0 159951.0 165126.0 165911.0 171327.0 1180.0 1201.9 1250.6 1256.7 1257.4 19051.6 19280.5 19966.8 19985.7 20063.2 66100.7 66345.3 66378.3 66672.7 67810.3 90347.5 90505.6 90974.5 92549.0 92976.5 11179.0 11350.9 11554.8 11726.5 11864.6 10931.9 11952.9 12481.6 12654.0 12821.6 10682.1 10872.2 11117.4 11384.4 11450.5 10359.5 10379.5 10571.3 10675.2 10720.8 9536.7 9619.8 10745.1 10777.0 10860.6 10186.6 11114.9 11303.7 11404.9 11464.3 10513.9 10584.4 10682.3 10917.6 10953.1 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | [#3] mootools.html Cycles:180 Average:6673.87 Median:3457.42 stddev:4281.43 (123.8%) stddev-sans-first:4274.50 02:29:19 INFO - PROCESS | 1731 | Values: 1315.2 1493.2 1551.7 1554.5 1562.6 10434.5 11065.0 11076.6 11090.5 11104.7 10644.5 11050.0 11204.7 11210.4 11210.4 2007.3 2382.8 2390.3 2390.4 2391.5 2296.7 2373.9 2374.6 2380.9 2385.6 2309.2 2342.9 2348.2 2375.2 2382.3 2301.5 2317.8 2322.2 2332.9 2339.7 2072.5 2212.0 2327.3 2339.0 2345.4 10950.7 11032.1 11039.6 11040.9 11057.4 5468.8 5653.2 5653.4 5663.5 5670.6 3139.0 3409.2 3505.6 3581.4 3619.3 1115.8 1144.5 1172.3 1176.2 1192.6 15644.7 15665.8 15720.8 15758.7 15775.4 3786.8 3864.8 3928.9 4548.7 4568.0 11536.6 11561.4 11564.1 11564.2 11584.6 5538.5 5549.2 5556.1 5558.9 5569.2 5018.4 5022.1 5051.1 5059.7 5124.2 3214.0 3710.6 3769.9 3792.4 3828.6 882.3 944.4 982.1 988.3 989.9 639.8 736.4 756.6 776.5 804.9 5048.9 6310.2 6531.2 6554.1 6602.1 897.8 946.9 950.0 960.4 989.9 3645.8 3761.6 4067.6 4077.5 4153.3 13376.0 13827.0 13834.2 13837.9 13856.0 11619.7 12053.4 13682.3 13741.0 13763.5 8752.1 8948.6 10162.0 10204.6 10405.1 6833.1 6854.5 6894.8 6944.3 7043.2 12127.3 12364.6 12544.2 12547.5 12579.0 12452.8 12464.9 12638.8 12727.2 12795.5 9312.4 9348.7 9383.7 9524.0 9548.0 10282.4 10363.7 10439.9 10481.3 10560.7 8918.6 8945.9 8973.1 8975.6 9038.7 8656.8 8742.5 8858.8 8874.3 8912.6 6394.4 6753.3 7846.4 7975.5 8061.7 5816.2 5865.0 5869.4 6020.8 6108.0 6003.8 6014.8 6064.2 6076.7 6078.7 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | [#4] prototype.html Cycles:180 Average:4370.59 Median:4734.19 stddev:1229.29 (26.0%) stddev-sans-first:1195.46 02:29:19 INFO - PROCESS | 1731 | Values: 367.7 376.3 379.4 383.0 387.0 4952.6 4972.7 4993.0 5073.7 5079.0 4694.6 4768.7 4809.8 4828.6 4953.7 3540.4 4019.8 4175.2 4189.9 4210.9 3271.4 3478.9 3841.6 3946.3 4207.2 3523.9 3808.0 4016.9 4192.0 4220.4 3504.8 4042.3 4188.3 4232.6 4239.9 3565.4 3701.6 3759.2 3761.3 3827.8 3923.8 4476.9 4720.0 4753.5 4826.7 4252.9 4475.4 4781.7 4845.0 4851.0 4306.6 5048.9 5202.5 5211.7 5331.6 3374.4 3731.6 3997.4 4264.8 4335.1 4390.7 4490.0 4852.9 5434.7 5731.0 4093.7 4190.7 4192.7 4254.4 4277.6 4091.1 4386.9 4440.4 4449.2 4452.9 3692.9 3707.6 4326.1 4697.0 4699.9 4734.5 4788.9 4871.7 5200.6 5354.1 1311.3 1510.0 1515.9 1525.0 1535.5 1018.3 1021.4 1050.5 1128.6 1131.8 4301.5 4889.7 4893.9 4944.3 4998.5 4515.8 4912.9 5521.3 5538.8 5559.2 4311.9 4600.8 4646.8 4733.9 4956.6 2401.7 2549.4 2625.1 2794.5 2847.0 4866.0 5144.1 5155.9 5604.0 5647.2 4718.0 4776.2 5096.8 5583.3 5655.2 4894.0 5538.4 5551.6 5614.0 5666.4 4944.4 5151.6 5345.3 5447.3 5478.8 4722.0 4742.2 4837.9 5337.2 5367.5 4948.8 5619.2 5645.4 5657.9 5718.9 4330.5 4409.0 4413.2 4416.8 4435.3 4926.1 5547.6 5579.7 5618.7 5627.4 4125.1 4741.3 4973.7 5310.8 5373.2 4952.3 5332.6 5344.9 5394.1 5404.3 4692.3 4873.6 5175.9 5224.2 5231.8 4462.8 5014.4 5272.4 5276.5 5368.2 4260.4 5161.4 5207.8 5209.5 5252.6 02:29:19 INFO - PROCESS | 1731 | 02:29:19 INFO - PROCESS | 1731 | [#5] yui.html Cycles:180 Average:19215.14 Median:22121.78 stddev:27195.88 (122.9%) stddev-sans-first:27236.71 02:29:19 INFO - PROCESS | 1731 | Values: 719.0 738.3 774.1 826.9 840.8 15177.2 15964.6 16007.8 16020.2 16026.7 11053.5 14676.6 14694.3 14802.3 14908.7 934.6 935.2 955.7 959.5 961.3 933.0 950.5 952.5 952.6 955.3 1005.1 1008.7 1010.9 1014.3 1026.3 1003.5 1004.2 1004.5 1005.3 1006.8 750.1 751.4 751.6 753.2 753.8 15533.5 15651.5 15849.4 15945.0 15971.7 13869.7 14008.4 14025.8 14141.4 14237.6 10622.6 11249.7 11790.0 11807.4 12079.1 4389.2 4430.9 4454.4 4517.8 4573.1 106551.0 106851.0 111569.9 111824.4 112517.0 10523.9 10617.1 10718.2 10921.0 11192.7 20689.9 21339.6 21362.9 21366.0 21368.5 7486.3 7491.9 7611.0 7625.5 7786.1 4184.5 4260.7 4336.2 4354.3 4425.3 3359.0 3433.3 3435.7 3436.1 3454.6 541.7 615.4 625.2 631.6 651.1 551.1 552.2 561.3 573.8 574.7 9485.7 9948.8 10011.9 10090.9 10118.5 569.8 594.8 596.6 601.4 601.4 4170.8 4470.6 4773.5 4837.9 4895.8 87572.6 90489.0 90764.6 93149.0 97090.5 47441.5 66367.7 72173.6 84878.0 88633.6 73315.6 74620.0 74726.3 76138.2 77013.0 10118.7 11022.2 11117.0 11256.3 11294.3 40789.0 41561.8 42088.0 42398.8 42538.4 58231.0 58335.1 58360.0 58424.0 58550.4 11973.8 12290.8 12337.6 12636.4 12646.7 11751.9 14884.4 15150.6 15346.2 15349.8 12846.7 13162.2 13929.3 13934.8 13958.2 12570.2 13486.1 13655.3 13764.0 13769.0 12770.2 13036.7 13058.9 13651.3 13921.7 9242.8 9665.7 9833.8 9839.5 9895.4 8705.5 9342.6 10455.7 10529.2 11414.5 02:29:19 INFO - PROCESS | 1731 | -------- Summary: end -------- 02:29:19 INFO - PROCESS | 1731 | 02:29:20 INFO - TEST-INFO | 1731: exit 0 02:29:20 INFO - TEST-OK | dromaeo_css | took 1119538ms 02:29:20 INFO - TEST-START | kraken 02:29:20 INFO - operating with platform_type : mac_ 02:29:20 INFO - Initialising browser for kraken test... 02:29:20 INFO - TEST-INFO | started process 1735 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmp7k237d/profile http://localhost:49241/getInfo.html) 02:29:22 INFO - PROCESS | 1735 | __metrics Screen width/height:1600/1200 02:29:22 INFO - PROCESS | 1735 | colorDepth:24 02:29:22 INFO - PROCESS | 1735 | Browser inner width/height: 1024/689 02:29:22 INFO - PROCESS | 1735 | __metrics 02:29:22 INFO - PROCESS | 1735 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:29:22 INFO - PROCESS | 1735 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:29:23 INFO - TEST-INFO | 1735: exit 0 02:29:23 INFO - Browser initialized. 02:29:23 INFO - Running cycle 1/1 for kraken test... 02:29:23 INFO - TEST-INFO | started process 1737 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/tmp7k237d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:35 INFO - PROCESS | 1737 | RSS: Main: 215625728 02:29:35 INFO - PROCESS | 1737 | 02:29:38 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 02:29:39 INFO - PROCESS | 1737 | RSS: Main: 268328960 02:29:39 INFO - PROCESS | 1737 | 02:29:41 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 02:29:42 INFO - PROCESS | 1737 | RSS: Main: 278908928 02:29:42 INFO - PROCESS | 1737 | 02:29:45 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 02:29:46 INFO - PROCESS | 1737 | RSS: Main: 375648256 02:29:46 INFO - PROCESS | 1737 | 02:29:48 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 02:29:48 INFO - PROCESS | 1737 | RSS: Main: 278056960 02:29:48 INFO - PROCESS | 1737 | 02:29:51 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:29:51 INFO - PROCESS | 1737 | RSS: Main: 278945792 02:29:51 INFO - PROCESS | 1737 | 02:29:54 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 02:29:55 INFO - PROCESS | 1737 | RSS: Main: 318779392 02:29:55 INFO - PROCESS | 1737 | 02:29:58 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 02:29:58 INFO - PROCESS | 1737 | RSS: Main: 321159168 02:29:58 INFO - PROCESS | 1737 | 02:30:01 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 02:30:02 INFO - PROCESS | 1737 | RSS: Main: 317599744 02:30:02 INFO - PROCESS | 1737 | 02:30:04 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:30:04 INFO - PROCESS | 1737 | RSS: Main: 295813120 02:30:04 INFO - PROCESS | 1737 | 02:30:06 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:30:07 INFO - PROCESS | 1737 | RSS: Main: 300564480 02:30:07 INFO - PROCESS | 1737 | 02:30:09 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:30:09 INFO - PROCESS | 1737 | RSS: Main: 302841856 02:30:09 INFO - PROCESS | 1737 | 02:30:12 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:30:13 INFO - PROCESS | 1737 | RSS: Main: 319754240 02:30:13 INFO - PROCESS | 1737 | 02:30:16 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:30:17 INFO - PROCESS | 1737 | RSS: Main: 312377344 02:30:17 INFO - PROCESS | 1737 | 02:30:18 INFO - PROCESS | 1737 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 02:30:19 INFO - PROCESS | 1737 | RSS: Main: 357347328 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | __start_tp_report 02:30:19 INFO - PROCESS | 1737 | _x_x_mozilla_page_load 02:30:19 INFO - PROCESS | 1737 | _x_x_mozilla_page_load_details 02:30:19 INFO - PROCESS | 1737 | |i|pagename|runs| 02:30:19 INFO - PROCESS | 1737 | |0;ai-astar;117;118;101;161;102;170;173;173;116;116 02:30:19 INFO - PROCESS | 1737 | |1;audio-beat-detection;170;134;109;142;109;109;108;107;128;106 02:30:19 INFO - PROCESS | 1737 | |2;audio-dft;184;181;183;183;196;183;182;182;185;178 02:30:19 INFO - PROCESS | 1737 | |3;audio-fft;80;60;61;93;63;61;59;61;77;60 02:30:19 INFO - PROCESS | 1737 | |4;audio-oscillator;85;95;121;101;68;68;105;100;99;94 02:30:19 INFO - PROCESS | 1737 | |5;imaging-gaussian-blur;88;88;89;89;89;88;88;88;89;88 02:30:19 INFO - PROCESS | 1737 | |6;imaging-darkroom;95;95;95;95;95;97;95;95;95;96 02:30:19 INFO - PROCESS | 1737 | |7;imaging-desaturate;83;87;85;85;85;84;86;84;86;84 02:30:19 INFO - PROCESS | 1737 | |8;json-parse-financial;59;59;57;60;65;64;64;64;63;63 02:30:19 INFO - PROCESS | 1737 | |9;json-stringify-tinderbox;67;64;63;62;59;58;57;58;58;59 02:30:19 INFO - PROCESS | 1737 | |10;stanford-crypto-aes;82;77;72;83;90;98;98;98;82;85 02:30:19 INFO - PROCESS | 1737 | |11;stanford-crypto-ccm;136;138;134;138;146;141;129;141;200;158 02:30:19 INFO - PROCESS | 1737 | |12;stanford-crypto-pbkdf2;203;217;178;163;165;164;162;166;164;167 02:30:19 INFO - PROCESS | 1737 | |13;stanford-crypto-sha256-iterative;65;65;66;65;64;65;62;63;63;63 02:30:19 INFO - PROCESS | 1737 | __end_tp_report 02:30:19 INFO - PROCESS | 1737 | __start_cc_report 02:30:19 INFO - PROCESS | 1737 | _x_x_mozilla_cycle_collect,2169 02:30:19 INFO - PROCESS | 1737 | __end_cc_report 02:30:19 INFO - PROCESS | 1737 | __startTimestamp1493631019229__endTimestamp 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | ------- Summary: start ------- 02:30:19 INFO - PROCESS | 1737 | Number of tests: 14 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#0] ai-astar Cycles:10 Average:134.70 Median:139.50 stddev:30.49 (21.9%) stddev-sans-first:31.66 02:30:19 INFO - PROCESS | 1737 | Values: 117.0 118.0 101.0 161.0 102.0 170.0 173.0 173.0 116.0 116.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:118.50 stddev:21.26 (17.9%) stddev-sans-first:13.82 02:30:19 INFO - PROCESS | 1737 | Values: 170.0 134.0 109.0 142.0 109.0 109.0 108.0 107.0 128.0 106.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#2] audio-dft Cycles:10 Average:183.70 Median:183.00 stddev:4.72 (2.6%) stddev-sans-first:5.00 02:30:19 INFO - PROCESS | 1737 | Values: 184.0 181.0 183.0 183.0 196.0 183.0 182.0 182.0 185.0 178.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#3] audio-fft Cycles:10 Average:67.50 Median:62.00 stddev:11.68 (18.8%) stddev-sans-first:11.48 02:30:19 INFO - PROCESS | 1737 | Values: 80.0 60.0 61.0 93.0 63.0 61.0 59.0 61.0 77.0 60.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#4] audio-oscillator Cycles:10 Average:93.60 Median:76.50 stddev:16.30 (21.3%) stddev-sans-first:16.99 02:30:19 INFO - PROCESS | 1737 | Values: 85.0 95.0 121.0 101.0 68.0 68.0 105.0 100.0 99.0 94.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:88.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 02:30:19 INFO - PROCESS | 1737 | Values: 88.0 88.0 89.0 89.0 89.0 88.0 88.0 88.0 89.0 88.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#6] imaging-darkroom Cycles:10 Average:95.30 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 02:30:19 INFO - PROCESS | 1737 | Values: 95.0 95.0 95.0 95.0 95.0 97.0 95.0 95.0 95.0 96.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:1.20 (1.4%) stddev-sans-first:1.05 02:30:19 INFO - PROCESS | 1737 | Values: 83.0 87.0 85.0 85.0 85.0 84.0 86.0 84.0 86.0 84.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:63.50 stddev:2.78 (4.4%) stddev-sans-first:2.76 02:30:19 INFO - PROCESS | 1737 | Values: 59.0 59.0 57.0 60.0 65.0 64.0 64.0 64.0 63.0 63.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:3.31 (5.5%) stddev-sans-first:2.54 02:30:19 INFO - PROCESS | 1737 | Values: 67.0 64.0 63.0 62.0 59.0 58.0 57.0 58.0 58.0 59.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:87.50 stddev:9.22 (10.5%) stddev-sans-first:9.63 02:30:19 INFO - PROCESS | 1737 | Values: 82.0 77.0 72.0 83.0 90.0 98.0 98.0 98.0 82.0 85.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:141.00 stddev:20.47 (14.5%) stddev-sans-first:21.38 02:30:19 INFO - PROCESS | 1737 | Values: 136.0 138.0 134.0 138.0 146.0 141.0 129.0 141.0 200.0 158.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.90 Median:166.50 stddev:19.31 (11.6%) stddev-sans-first:17.61 02:30:19 INFO - PROCESS | 1737 | Values: 203.0 217.0 178.0 163.0 165.0 164.0 162.0 166.0 164.0 167.0 02:30:19 INFO - PROCESS | 1737 | 02:30:19 INFO - PROCESS | 1737 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:65.00 stddev:1.29 (2.0%) stddev-sans-first:1.32 02:30:19 INFO - PROCESS | 1737 | Values: 65.0 65.0 66.0 65.0 64.0 65.0 62.0 63.0 63.0 63.0 02:30:19 INFO - PROCESS | 1737 | -------- Summary: end -------- 02:30:19 INFO - PROCESS | 1737 | 02:30:20 INFO - TEST-INFO | 1737: exit 0 02:30:20 INFO - TEST-OK | kraken | took 60044ms 02:30:20 INFO - SUITE-END | took 1179s 02:30:20 INFO - Completed test suite (00:19:39) 02:30:20 INFO - javascript benchmark 02:30:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7641.686123966507, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3397.8640854365826, 3422.74329425293, 3428.7771294865834, 3449.189440481488, 3486.0761898096994, 5958.940410595893, 5979.46184843364, 5984.251968503937, 5995.950708625989, 6020.939790602092, 5947.583669143161, 6030.065339872316, 6035.366286539905, 6043.697815109238, 6062.484688801457, 5322.787088516455, 5358.571314294866, 5390.9191362129595, 5444.265024221739, 5480.725963701799, 5273.630845840793, 5281.102212623863, 5324.094903866333, 5461.399246082928, 5478.233047373371, 5372.113601255788, 5388.353397592295, 5417.26866872971, 5432.2123292122615, 5461.42655021223, 5435.891282174334, 5436.401995780461, 5451.509364157205, 5451.863703407407, 5463.508284254416, 5192.8961420771375, 5322.893542129174, 5381.946180538183, 5392.0, 5453.754581043862, 4796.52034796521, 5866.472017518444, 5943.4056594340655, 5997.460228579405, 6026.397360263939, 6344.111824344595, 6369.235691716977, 6373.6175829450385, 6379.936200638027, 6400.967995160041, 6023.126646636257, 6392.520560957901, 6402.615843049385, 6407.519436042269, 6414.871702565969, 5885.970570147122, 5941.405859414111, 5948.0, 5964.1650168976375, 6167.568270221086, 6349.428551430394, 6404.519661025442, 6439.0, 6447.677616119176, 6514.771982980573, 6366.363363663597, 6377.489800815923, 6381.680915954184, 6382.840428989312, 6388.393102655334, 6418.550701450947, 6427.164468619049, 6429.3570642936265, 6441.806745797634, 6449.935500645028, 6231.2522497300615, 6250.468710159687, 6264.780732674334, 6358.1416508771645, 6365.809025729235, 6358.7456501740335, 6386.680665966776, 6399.552031357854, 6401.935980640134, 6411.51913606472, 5103.642745007888, 5138.743062846843, 5139.743012849342, 5152.819651312186, 5167.121589329748, 4486.663500237495, 4490.057088011554, 4573.153966516205, 4604.769761511978, 4650.302454631832, 5805.332386775491, 5808.564357673192, 5812.88374232513, 5817.650940943472, 5820.126980952891, 6292.0, 6322.873542529123, 6366.872662546816, 6371.681415929185, 6375.3624637536805, 5893.410658934073, 5936.28764548257, 5950.732217050243, 5966.254218222723, 5969.611975221596, 5632.239647647515, 5632.3804381518, 5636.605437619327, 5640.238567793377, 5651.8304450866535, 6294.0, 6361.332060133619, 6441.967790161019, 6541.967290163614, 6572.507061970371, 6369.7452101915405, 6380.9361906380345, 6383.585066970632, 6385.0, 6398.1362516059735, 6542.640154791532, 6554.606723596599, 6558.1802274715665, 6568.113304703804, 6579.407853293035, 6210.813675589739, 6237.4698150657705, 6321.399467050623, 6355.618662880242, 6385.7126429309865, 6198.969005154945, 6325.114483972339, 6425.293217746138, 6430.742770289136, 6441.742330306735, 6355.841103972438, 6360.395762402564, 6368.395002474757, 6374.0, 6375.234971803413, 6047.697615119315, 6111.755529778759, 6337.144485494399, 6356.586821856565, 6368.808935731936, 5120.513551212629, 5993.460588547052, 6119.228375855631, 6255.499560035278, 6262.091996660368, 5480.068388374066, 6341.270753863365, 6353.174087368612, 6360.745570177141, 6363.0, 5956.434138756811, 6176.69116544162, 6232.968835155976, 6245.344238854855, 6247.344028877084], "value": 5872.54562747383, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.3152852083302, 760.9239076092388, 812.9715909374642, 875.6366108065154, 891.915268049535, 27884.3028924277, 40148.79925600372, 40739.38890916635, 40774.388384174184, 40923.9769005775, 42042.0, 42330.153396932044, 42345.67098809564, 42365.57634423662, 42403.0, 7473.476856620039, 9141.908580914172, 9283.350165488418, 9291.628334866597, 9306.627734890597, 7398.223186565414, 10989.230753847234, 11227.887721122766, 11316.094712422986, 11329.716757081056, 11184.105271578253, 11211.551537938514, 11213.775724485506, 11219.0, 11248.38133902635, 11126.554937802519, 11164.218504704673, 11173.938475844781, 11178.944105279503, 11185.0, 10915.126789856871, 10951.945240273828, 10976.615818446397, 10992.450377481175, 10995.835062474069, 39130.82607521779, 39237.80381098076, 39383.60616393828, 39400.408993865116, 39453.60546394557, 3832.8689697108616, 3846.134619710571, 3853.7880416577077, 3896.473976013259, 3917.6474117329576, 25850.74149258521, 27045.86477067602, 27836.721632783614, 28959.276018099506, 29171.0, 3074.846257687129, 3148.7323577495713, 3194.632617249, 3201.08769000836, 3303.8513266903046, 354413.0, 365468.0, 365497.0, 365906.9999999973, 366117.1694141539, 51429.742851285504, 51469.227961580604, 51570.74214628903, 51628.22557661638, 51783.74108129435, 54391.728041359536, 54501.0, 54522.0, 54552.0, 54591.72704136533, 14515.201663908587, 14612.196329201783, 14633.268336583129, 14640.975131740888, 14648.780268295985, 11080.667579972614, 12752.426140823685, 12760.979121670243, 12767.297798620979, 12773.680657983625, 10181.643642472478, 10229.539670714836, 14003.229822359664, 14144.656257655506, 14377.353019114165, 5329.8934021319355, 5988.640681559121, 6000.309964354155, 6003.699815009232, 6021.42796434338, 1111.777644471109, 1137.4653912661036, 1151.3955173533961, 1153.3368313219933, 1192.9642110736693, 24810.255692329258, 25807.354816129748, 26184.73815261861, 27426.451470970467, 27910.441791164063, 869.9217070463689, 955.245356168633, 1041.6822869024868, 1121.5513794482108, 1285.517930775959, 13627.182369057888, 13790.448382064806, 13992.300384980914, 14037.0, 14379.065360751518, 506156.8768624681, 508893.0, 509767.0, 510343.0, 512711.0, 46607.766961164976, 48053.0, 56542.86914261774, 57027.42972570221, 57825.554361140465, 1415.2074838090703, 1442.834074081473, 1446.8119144510936, 1456.6649670575828, 1457.8979471437322, 28868.0, 29305.56041659334, 29653.5551966725, 30336.39327213399, 30363.544546831374, 46299.0, 46380.840478986946, 46393.76803116098, 46463.0, 46466.30300545562, 469798.0, 469827.0, 470962.0, 471116.0, 476208.0, 29701.40597188001, 30208.84895575596, 31055.68944310528, 31137.688623114384, 32528.0, 37479.063023424635, 38353.616463835, 38640.806795965844, 38811.6118838808, 39086.804565976985, 27189.320266993484, 27530.862345688944, 28381.006664766635, 28467.28831779222, 28809.70356333928, 25981.936047059735, 27231.183064508095, 27635.585466218425, 28177.15468535865, 28532.286692832844, 31525.0, 32216.516752249205, 32631.510527341634, 32802.67197327996, 32810.17974550655, 27413.0, 27432.0, 27436.0, 27507.724922751317, 28629.0, 20634.484137896074, 20775.480612984193, 21429.678554822003, 22568.984395702562, 22674.979625916545], "value": 18715.59600095031, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.0132664359172, 400.8817398867334, 412.66914201699524, 430.4382780471485, 435.36436802268685, 12938.547150849723, 16759.581010474743, 19657.705134422977, 20471.590568188665, 21031.47421314468, 23265.883670581665, 23739.762602373972, 24531.63202551963, 24537.0, 24825.50348993019, 5336.439673834249, 6224.5642805003445, 6815.556988795737, 7055.0, 7123.750668726596, 7060.82347941303, 7073.080499535054, 7141.500094993353, 7149.213586505481, 7152.964235178817, 6858.862822743542, 6932.0, 6995.125609298838, 7035.36681698647, 7061.293870612949, 6901.0, 7003.5097543172, 7068.36384725372, 7076.6815493302665, 7423.962880185564, 7490.6629201686055, 7560.319571238615, 7745.728899488491, 7761.22387761225, 7773.494722842997, 27589.724102758915, 28184.718152818416, 28280.29299267535, 30137.0, 30335.393292134035, 3425.092350527112, 3430.7426942979127, 3509.91225219369, 3547.7161827053774, 3595.53258076451, 16264.186790660538, 17278.65442691152, 17955.0, 18030.819691803044, 18373.632527349513, 2026.23769020834, 2038.6489732315686, 2045.1103769860158, 2109.7025329422295, 2143.6248656485054, 127436.72563274342, 165307.0, 169477.30522694738, 169735.0, 170926.14536927236, 18625.81374186268, 24797.87601061983, 24800.875995619906, 24842.50314993726, 25466.74533254681, 43712.78143609261, 43715.78142109269, 44322.0, 45055.54944450514, 45409.091818163455, 11713.0, 11740.647780566596, 11805.822912656316, 11833.349165795791, 12064.758704825854, 9354.953225233967, 9368.67209647659, 9385.15533601979, 9523.619055237852, 9575.616975321049, 11546.960773530423, 11650.6504804856, 11655.067594592412, 11717.828217178385, 11770.705732356588, 4503.041177087977, 4566.3150527420485, 4576.748278844629, 4603.516630753723, 4603.8849028773875, 1014.3609525998721, 1036.1400037968467, 1054.3884546962743, 1055.7255113670346, 1066.0671912076932, 20535.58928821445, 21783.891080544498, 21812.89093554554, 21866.781332186474, 21918.23286184976, 1052.315994603514, 1058.2433560004633, 1058.9894101058892, 1088.6353071721062, 1103.8509801176738, 11101.056410205061, 11380.487878045507, 11422.771544569063, 11445.313281202987, 11463.082953363712, 584352.0, 694523.0, 733791.0, 737304.0, 752344.6679366196, 159704.0, 159951.0, 165126.0, 165911.0, 171327.0, 1179.9941000294943, 1201.8677945425995, 1250.5998080614115, 1256.7360854220533, 1257.4278703190027, 19051.61896762085, 19280.517987050436, 19966.800331996496, 19985.700214497087, 20063.197472101536, 66100.6694966522, 66345.33654663392, 66378.33621663721, 66672.6666366665, 67810.3218967804, 90347.54826225826, 90505.64241536515, 90974.5451272766, 92549.0, 92976.53511732668, 11178.99389054989, 11350.94324528372, 11554.76890462203, 11726.530938762355, 11864.584739534075, 10931.906809319005, 11952.940235299116, 12481.56314528987, 12654.0, 12821.615351539469, 10682.145428365868, 10872.184586156229, 11117.444127793417, 11384.37385943781, 11450.541978321107, 10359.533820977924, 10379.481025948522, 10571.2600117991, 10675.199360047873, 10720.785584288427, 9536.713898583053, 9619.807603848023, 10745.140388769036, 10777.0, 10860.565577377132, 10186.643467478603, 11114.944425277821, 11303.660890173307, 11404.94297528507, 11464.25482343645, 10513.947430263106, 10584.417857017937, 10682.305650132717, 10917.563297468012, 10953.123750100132], "value": 12154.727375587243, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.1911574381754, 1493.1936754152757, 1551.7439622462293, 1554.4870192836365, 1562.570293169378, 10434.478276086194, 11065.004149626531, 11076.556937722491, 11090.500927458264, 11104.722381940435, 10644.467776611176, 11050.005499505043, 11204.66386008421, 11210.383428911407, 11210.439478026086, 2007.2874129683971, 2382.8451150675237, 2390.282915125464, 2390.378501589582, 2391.485830546432, 2296.712910886139, 2373.9406514837096, 2374.631932050535, 2380.8690522021284, 2385.6063749481314, 2309.203324852923, 2342.8594284342994, 2348.178137651825, 2375.2280508834697, 2382.3091303522046, 2301.5396920615776, 2317.7913987741185, 2322.245270287155, 2332.8600283982846, 2339.6841426407386, 2072.4818795301176, 2212.048819007826, 2327.325075728037, 2338.988305058481, 2345.4253707841517, 10950.726231844188, 11032.062274706659, 11039.558417663284, 11040.944795276053, 11057.391843448608, 5468.835934921959, 5653.152027195953, 5653.406392328788, 5663.546916246691, 5670.6030577859565, 3138.9686103139134, 3409.1988382730037, 3505.6494350564994, 3581.3732596795717, 3619.27614477103, 1115.8437818705306, 1144.4764840409698, 1172.2731906218198, 1176.2295696318924, 1192.648168790209, 15644.687106257925, 15665.765013524806, 15720.842791572053, 15758.68482630341, 15775.368985240577, 3786.753860999026, 3864.821229525022, 3928.9017774555864, 4548.681592288508, 4568.040711450566, 11536.596219132458, 11561.364124973039, 11564.13269004828, 11564.190506664621, 11584.594539191256, 5538.501534861882, 5549.19536667181, 5556.111022236423, 5558.888822223533, 5569.248151499577, 5018.397792264952, 5022.070916880389, 5051.1413059779925, 5059.696418214919, 5124.205748109049, 3213.9839300803346, 3710.6289371063217, 3769.9434508482395, 3792.3742582474133, 3828.5979972102527, 882.342654722236, 944.3625552751865, 982.1062832822098, 988.2588058955783, 989.8762654668167, 639.8336432527483, 736.3667246168291, 756.606564586423, 776.5301992294693, 804.8913396691489, 5048.899022019539, 6310.242770867525, 6531.248906375828, 6554.115194448688, 6602.108715323466, 897.8428774964356, 946.8674385586023, 949.9572519236651, 960.4093482508207, 989.9407633831731, 3645.76302540334, 3761.586225515191, 4067.5525692173837, 4077.4903137107863, 4153.314703074019, 13376.0, 13827.0, 13834.169949802843, 13837.86162138386, 13856.0, 11619.6514104577, 12053.433053703138, 13682.31588420575, 13741.0, 13763.518276860263, 8752.081031491729, 8948.642054317754, 10162.0346067125, 10204.642837500653, 10405.06354428105, 6833.145856767904, 6854.520183587101, 6894.827629309308, 6944.305569443015, 7043.154821421463, 12127.332996685267, 12364.567240146911, 12544.184627999151, 12547.498100075894, 12579.0, 12452.750944981231, 12464.875351246372, 12638.810417843555, 12727.172733772275, 12795.488180472677, 9312.394694344846, 9348.672796452092, 9383.718488445358, 9524.0, 9547.952260238655, 10282.434466104436, 10363.740906477398, 10439.94780026095, 10481.318714283547, 10560.683179504627, 8918.554072296229, 8945.910540894507, 8973.102689731235, 8975.596098175467, 9038.683646072355, 8656.78358041054, 8742.475451472932, 8858.822823543363, 8874.29005679557, 8912.55437228123, 6394.360563943568, 6753.2909044549615, 7846.411519135973, 7975.521468711896, 8061.717839875811, 5816.156657284755, 5865.032269675456, 5869.413058694096, 6020.759169633166, 6107.969460152671, 6003.8198854034445, 6014.789482368096, 6064.241969753781, 6076.726547305293, 6078.696065196634], "value": 5004.604789713048, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.71134659292454, 376.33201068104114, 379.44790330069753, 383.0, 386.98452061917504, 4952.597989974859, 4972.701637901729, 4992.950070499303, 5073.7209453480045, 5078.9746051269785, 4694.577488026077, 4768.73771942543, 4809.791432496566, 4828.56542911138, 4953.73679711674, 3540.4335306350927, 4019.77891215983, 4175.227582897159, 4189.863169783402, 4210.873673789792, 3271.4275001874576, 3478.8782392616263, 3841.635044670752, 3946.2699400610836, 4207.15856828634, 3523.8766643167496, 3807.9809600951958, 4016.919661606766, 4192.0, 4220.396249425214, 3504.8072356020407, 4042.312806822847, 4188.308929026707, 4232.619064284198, 4239.851605193819, 3565.3938830398893, 3701.6298370163036, 3759.2481503699096, 3761.322961866863, 3827.789471579062, 3923.763454830748, 4476.865694029152, 4720.0, 4753.453352864431, 4826.710397376133, 4252.872413827559, 4475.361045083186, 4781.6891902026255, 4844.951550484485, 4850.975745121287, 4306.6124048835445, 5048.873778155576, 5202.453742357074, 5211.739413029333, 5331.5734741220995, 3374.3588718143474, 3731.5522137343423, 3997.400389941503, 4264.786760661955, 4335.095486024371, 4390.692651514363, 4490.0, 4852.902941941176, 5434.701091439969, 5731.0, 4093.692973027035, 4190.706650534495, 4192.673066448089, 4254.446921900133, 4277.593628605277, 4091.1204091120553, 4386.929149595232, 4440.378347031418, 4449.199144154083, 4452.866414007585, 3692.9261414771554, 3707.5736290326417, 4326.113146804897, 4697.003773396293, 4699.882502937454, 4734.502877197845, 4788.880277993008, 4871.7089971157675, 5200.557952574073, 5354.063038968243, 1311.2919023727081, 1509.9849001510065, 1515.9393624255126, 1524.9794022919675, 1535.5470136309816, 1018.332991890313, 1021.406605695118, 1050.4590136079926, 1128.5824245029391, 1131.7566723154562, 4301.505326887385, 4889.682170658896, 4893.898872753602, 4944.332515110414, 4998.500449865099, 4515.848458643025, 4912.862904636495, 5521.309836270466, 5538.759825502046, 5559.193916882029, 4311.913761724811, 4600.815967361335, 4646.767661616906, 4733.881652958704, 4956.578690811321, 2401.711794584626, 2549.388146844744, 2625.1492697648114, 2794.4550812591756, 2846.985765071203, 4865.978144589605, 5144.125498665236, 5155.896882062338, 5603.971980140073, 5647.237622920935, 4718.0328032753205, 4776.23580227162, 5096.770645320969, 5583.274174357308, 5655.211008624232, 4894.037356321814, 5538.363088244822, 5551.583631227674, 5614.017546929271, 5666.356756540722, 4944.406671199479, 5151.562117220078, 5345.33183352081, 5447.330807904019, 5478.780848766164, 4722.008378240608, 4742.170120229015, 4837.911469919381, 5337.172738225581, 5367.490088441592, 4948.826791062295, 5619.1508816460855, 5645.379008309001, 5657.896710141483, 5718.885622287614, 4330.52364239927, 4409.0, 4413.22768515515, 4416.801243943966, 4435.334699795057, 4926.137925862888, 5547.55619550443, 5579.74891129892, 5618.6628802272, 5627.380988091389, 4125.050588829002, 4741.312509686145, 4973.73169841688, 5310.840674779763, 5373.205932914941, 4952.331435256195, 5332.626716129834, 5344.866378340417, 5394.087192915938, 5404.297441332602, 4692.319613656069, 4873.610111191168, 5175.92236116451, 5224.190250511177, 5231.77649498969, 4462.754548499864, 5014.373203349582, 5272.446393128666, 5276.459717296746, 5368.221607866915, 4260.382244574581, 5161.432242453252, 5207.791688332424, 5209.505097015777, 5252.581802913269], "value": 4018.435828590176, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.9820254493641, 738.2801768275929, 774.0672489649972, 826.9379796515261, 840.7855996720837, 15177.2411379431, 15964.600884977881, 16007.759883601726, 16020.198990050494, 16026.67946641068, 11053.502592383342, 14676.559703208894, 14694.265286735674, 14802.333894974725, 14908.7018259635, 934.6261495401843, 935.177044201102, 955.7323949294208, 959.4722902403685, 961.3126614470652, 932.9720108396759, 950.491487054426, 952.4951775558931, 952.5761036338832, 955.2930831184942, 1005.1205195453978, 1008.6570566007556, 1010.8635334229895, 1014.3406785589352, 1026.343140390151, 1003.5433877585681, 1004.1665417703289, 1004.5278719002057, 1005.271178395661, 1006.8489726541004, 750.1373420566338, 751.443931490693, 751.6392131776723, 753.2091304130641, 753.8153152477678, 15533.533993980085, 15651.530454086396, 15849.366025358971, 15945.0, 15971.680566388608, 13869.653258668513, 14008.369623366973, 14025.78961315581, 14141.434342626282, 14237.572872813833, 10622.62820801276, 11249.718757031058, 11790.0, 11807.350595717233, 12079.09406794494, 4389.209942210401, 4430.889227769299, 4454.37638730578, 4517.751523666197, 4573.131105090056, 106551.0, 106851.0, 111569.88430115677, 111824.4408777959, 112517.0, 10523.94738026305, 10617.081938762862, 10718.24972251935, 10921.0, 11192.664220073411, 20689.862057586677, 21339.573208536054, 21362.893185533972, 21366.0, 21368.465788355414, 7486.288802563748, 7491.887621685679, 7611.0, 7625.466217364842, 7786.065672119382, 4184.497860256789, 4260.659147268211, 4336.176126536011, 4354.281543545343, 4425.291953287458, 3358.9832050840077, 3433.261848702541, 3435.690787829107, 3436.1237884339835, 3454.5681789776277, 541.6641682157087, 615.4122812713847, 625.2309659119218, 631.5768435148461, 651.1209866680039, 551.1072063257549, 552.1855263486326, 561.3039830610014, 573.8106424879779, 574.6523353371234, 9485.715428537154, 9948.801023979624, 10011.949940250252, 10090.949545252226, 10118.494075296207, 569.8432930943941, 594.7591225553658, 596.6092209602717, 601.3685630088389, 601.4436646102338, 4170.77060761655, 4470.575295346936, 4773.474917759044, 4837.9032419351415, 4895.796573439451, 87572.5621371889, 90489.0, 90764.63853042209, 93149.0, 97090.51454742822, 47441.48569542416, 66367.66816165888, 72173.63913180401, 84878.0, 88633.55683221671, 73315.63342183361, 74620.0, 74726.25273747194, 76138.23861761422, 77013.0, 10118.747031323981, 11022.228444008842, 11116.989947190395, 11256.324620522795, 11294.265872718248, 40789.0, 41561.75314740562, 42088.0, 42398.788006061004, 42538.36192457178, 58231.0, 58335.12497312459, 58360.0, 58424.0, 58550.41449585449, 11973.76052478963, 12290.81563776562, 12337.568185113478, 12636.43136058898, 12646.683832904251, 11751.941240293743, 14884.404623814926, 15150.621234468785, 15346.232688365313, 15349.769753453484, 12846.697925773062, 13162.210267383987, 13929.303534823015, 13934.790978135134, 13958.162510249418, 12570.219487118255, 13486.12340197884, 13655.317234138452, 13764.0, 13769.0, 12770.169938953939, 13036.674083147996, 13058.934705326412, 13651.317434128452, 13921.721565568834, 9242.815143696955, 9665.661701840401, 9833.803323933338, 9839.508024598885, 9895.406275623485, 8705.521196334263, 9342.579583918876, 10455.738606534593, 10529.210309226992, 11414.543418263509], "value": 7411.186235500199, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1464.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 101.0, 161.0, 102.0, 170.0, 173.0, 173.0, 116.0, 116.0], "value": 134.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 134.0, 109.0, 142.0, 109.0, 109.0, 108.0, 107.0, 128.0, 106.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 183.0, 183.0, 196.0, 183.0, 182.0, 182.0, 185.0, 178.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 61.0, 93.0, 63.0, 61.0, 59.0, 61.0, 77.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 95.0, 121.0, 101.0, 68.0, 68.0, 105.0, 100.0, 99.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 89.0, 89.0, 88.0, 88.0, 88.0, 89.0, 88.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 97.0, 95.0, 95.0, 95.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 85.0, 85.0, 85.0, 84.0, 86.0, 84.0, 86.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 57.0, 60.0, 65.0, 64.0, 64.0, 64.0, 63.0, 63.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 62.0, 59.0, 58.0, 57.0, 58.0, 58.0, 59.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 72.0, 83.0, 90.0, 98.0, 98.0, 98.0, 82.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 134.0, 138.0, 146.0, 141.0, 129.0, 141.0, 200.0, 158.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 217.0, 178.0, 163.0, 165.0, 164.0, 162.0, 166.0, 164.0, 167.0], "value": 174.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 66.0, 65.0, 64.0, 65.0, 62.0, 63.0, 63.0, 63.0], "value": 64.1, "unit": "score"}]}]} 02:30:20 INFO - Return code: 0 02:30:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:30:20 ERROR - # TBPL SUCCESS # 02:30:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:20 INFO - 2017-05-01 02:30:20.591 screenresolution[1742:19719] starting screenresolution argv=screenresolution get 02:30:20 INFO - 2017-05-01 02:30:20.593 screenresolution[1742:19719] Display 0: 1600x1200x32@60 02:30:20 INFO - 2017-05-01 02:30:20.599 screenresolution[1743:19723] starting screenresolution argv=screenresolution list 02:30:20 INFO - Available Modes on Display 0 02:30:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:30:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:30:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:30:20 INFO - Intel Iris: 02:30:20 INFO - Chipset Model: Intel Iris 02:30:20 INFO - Type: GPU 02:30:20 INFO - Bus: Built-In 02:30:20 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:20 INFO - Vendor: Intel (0x8086) 02:30:20 INFO - Device ID: 0x0a2e 02:30:20 INFO - Revision ID: 0x0009 02:30:20 INFO - Displays: 02:30:20 INFO - Display: 02:30:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:30:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:20 INFO - Display Serial Number: 1600x1200 60 02:30:20 INFO - Main Display: Yes 02:30:20 INFO - Mirror: Off 02:30:20 INFO - Online: Yes 02:30:20 INFO - Rotation: Supported 02:30:20 INFO - Return code: 0 02:30:20 INFO - Running post-action listener: _resource_record_post_action 02:30:20 INFO - [mozharness: 2017-05-01 09:30:20.832993Z] Finished run-tests step (success) 02:30:20 INFO - Running post-run listener: _resource_record_post_run 02:30:20 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 32693248; Write bytes: 510602240; Read time: 189; Write time: 1562 02:30:20 INFO - TinderboxPrint: CPU usage
53.8% 02:30:20 INFO - TinderboxPrint: I/O read bytes / time
32,693,248 / 189 02:30:20 INFO - TinderboxPrint: I/O write bytes / time
510,602,240 / 1,562 02:30:20 INFO - TinderboxPrint: CPU idle
2,219.3 (46.2%) 02:30:20 INFO - TinderboxPrint: CPU system
956.5 (19.9%) 02:30:20 INFO - TinderboxPrint: CPU user
1,625.8 (33.9%) 02:30:20 INFO - TinderboxPrint: Swap in / out
236,687,360 / 0 02:30:20 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203858432; Write bytes: 199335936; Read time: 15655; Write time: 413 02:30:20 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 30714880; Write bytes: 300473344; Read time: 171; Write time: 1113 02:30:21 INFO - Running post-run listener: _upload_blobber_files 02:30:21 INFO - Blob upload gear active. 02:30:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:30:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:30:21 INFO - (blobuploader) - INFO - Open directory for files ... 02:30:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:30:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:30:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:22 INFO - (blobuploader) - INFO - Done attempting. 02:30:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:30:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:30:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:23 INFO - (blobuploader) - INFO - Done attempting. 02:30:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:30:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:24 INFO - (blobuploader) - INFO - Done attempting. 02:30:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:30:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:30:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:26 INFO - (blobuploader) - INFO - Done attempting. 02:30:26 INFO - (blobuploader) - INFO - Iteration through files over. 02:30:26 INFO - Return code: 0 02:30:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:30:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:30:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf3230b9f8e2b82b21ee2181c8343c1b7629d4b88a9ab63893f7c5f3ea6333fc0eeb6d7b02f7039b98a2e927df648566c63e64e00cd5201496e8450ff0312a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40cc511a14012a096c43c0c8a60c2fdbb2c84baedf386af1982b6749064ac58ff72e6a883099c0b797bf3d959c2f3671c2d0ba4498b2d9f28058c5f493f639", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06df7e623e7323c9e9133debaa50ff27d5f34ee08f942ec7001bc60dcaac258ba485283c6b19dfc3b9e124cc5c188058cab8d19be45307bfc92da4550ade817", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7967f346e5398d33c35d98943357bec20e4cdd6e8f86b6762c57f277dac25724656ebf0151864c48833ed4c0253790dc27bdff54b1589d30241f71a496498860"} 02:30:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:30:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:30:26 INFO - Contents: 02:30:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf3230b9f8e2b82b21ee2181c8343c1b7629d4b88a9ab63893f7c5f3ea6333fc0eeb6d7b02f7039b98a2e927df648566c63e64e00cd5201496e8450ff0312a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40cc511a14012a096c43c0c8a60c2fdbb2c84baedf386af1982b6749064ac58ff72e6a883099c0b797bf3d959c2f3671c2d0ba4498b2d9f28058c5f493f639", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06df7e623e7323c9e9133debaa50ff27d5f34ee08f942ec7001bc60dcaac258ba485283c6b19dfc3b9e124cc5c188058cab8d19be45307bfc92da4550ade817", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7967f346e5398d33c35d98943357bec20e4cdd6e8f86b6762c57f277dac25724656ebf0151864c48833ed4c0253790dc27bdff54b1589d30241f71a496498860"} 02:30:26 INFO - Running post-run listener: copy_logs_to_upload_dir 02:30:26 INFO - Copying logs to upload dir... 02:30:26 INFO - mkdir: /builds/slave/test/build/upload/logs 02:30:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.446529 ========= master_lag: 0.94 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-05-01 02:30:27.687337) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-01 02:30:27.695178) ========= 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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf3230b9f8e2b82b21ee2181c8343c1b7629d4b88a9ab63893f7c5f3ea6333fc0eeb6d7b02f7039b98a2e927df648566c63e64e00cd5201496e8450ff0312a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40cc511a14012a096c43c0c8a60c2fdbb2c84baedf386af1982b6749064ac58ff72e6a883099c0b797bf3d959c2f3671c2d0ba4498b2d9f28058c5f493f639", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06df7e623e7323c9e9133debaa50ff27d5f34ee08f942ec7001bc60dcaac258ba485283c6b19dfc3b9e124cc5c188058cab8d19be45307bfc92da4550ade817", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7967f346e5398d33c35d98943357bec20e4cdd6e8f86b6762c57f277dac25724656ebf0151864c48833ed4c0253790dc27bdff54b1589d30241f71a496498860"} build_url:https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010964 build_url: 'https://queue.taskcluster.net/v1/task/PCC-7aj7TpqkOBjHzYYAGg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf3230b9f8e2b82b21ee2181c8343c1b7629d4b88a9ab63893f7c5f3ea6333fc0eeb6d7b02f7039b98a2e927df648566c63e64e00cd5201496e8450ff0312a7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bb40cc511a14012a096c43c0c8a60c2fdbb2c84baedf386af1982b6749064ac58ff72e6a883099c0b797bf3d959c2f3671c2d0ba4498b2d9f28058c5f493f639", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b06df7e623e7323c9e9133debaa50ff27d5f34ee08f942ec7001bc60dcaac258ba485283c6b19dfc3b9e124cc5c188058cab8d19be45307bfc92da4550ade817", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7967f346e5398d33c35d98943357bec20e4cdd6e8f86b6762c57f277dac25724656ebf0151864c48833ed4c0253790dc27bdff54b1589d30241f71a496498860"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-01 02:30:27.737580) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:30:27.737927) ========= 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.TTVKMCJN9Y/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.7IjPU3DSkv/Listeners TMPDIR=/var/folders/t9/3mb18jg53rv4js3q7pg726c000000w/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.005158 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-01 02:30:27.786767) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-01 02:30:27.787074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-01 02:30:27.787387) ========= ========= Total master_lag: 1.12 =========