builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0296 starttime: 1492516008.63 results: success (0) buildid: 20170418031744 builduid: b98447929eb940aabbbe4a320363fe3f revision: 09e97b55acb30be1f8e13b0f637f9cf152658799 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:48.626295) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:48.626743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:48.627033) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:48.652682) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:48.653074) ========= 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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-04-18 04:46:48-- 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% 22.0M=0.001s 2017-04-18 04:46:49 (22.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.882921 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:49.586730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:49.587029) ========= 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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.021192 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:49.663247) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 04:46:49.663570) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 09e97b55acb30be1f8e13b0f637f9cf152658799 --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 09e97b55acb30be1f8e13b0f637f9cf152658799 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JOcjDYc2TK/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.Ofn5TAAip4/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-04-18 04:46:49,759 truncating revision to first 12 chars 2017-04-18 04:46:49,760 Setting DEBUG logging. 2017-04-18 04:46:49,760 attempt 1/10 2017-04-18 04:46:49,760 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/09e97b55acb3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-18 04:46:50,999 unpacking tar archive at: mozilla-aurora-09e97b55acb3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.504143 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 04:46:51.195336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:51.195703) ========= script_repo_revision: 09e97b55acb30be1f8e13b0f637f9cf152658799 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:51.196120) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:51.196407) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 04:46:51.224052) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-04-18 04:46:51.224442) ========= /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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:46:51 INFO - MultiFileLogger online at 20170418 04:46:51 in /builds/slave/test 04:46:51 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 04:46:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:46:51 INFO - {'append_to_log': False, 04:46:51 INFO - 'base_work_dir': '/builds/slave/test', 04:46:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:46:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:46:51 INFO - 'branch': 'Mozilla-Aurora', 04:46:51 INFO - 'buildbot_json_path': 'buildprops.json', 04:46:51 INFO - 'config_files': ('talos/mac_config.py',), 04:46:51 INFO - 'default_actions': ('clobber', 04:46:51 INFO - 'read-buildbot-config', 04:46:51 INFO - 'download-and-extract', 04:46:51 INFO - 'populate-webroot', 04:46:51 INFO - 'create-virtualenv', 04:46:51 INFO - 'install', 04:46:51 INFO - 'run-tests'), 04:46:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:46:51 INFO - 'download_minidump_stackwalk': True, 04:46:51 INFO - 'download_symbols': 'ondemand', 04:46:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:46:51 INFO - 'tooltool.py': '/tools/tooltool.py', 04:46:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:46:51 INFO - '/tools/misc-python/virtualenv.py')}, 04:46:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:46:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:46:51 INFO - 'gecko_profile': False, 04:46:51 INFO - 'gecko_profile_interval': 0, 04:46:51 INFO - 'installer_path': 'installer.exe', 04:46:51 INFO - 'log_level': 'info', 04:46:51 INFO - 'log_name': 'talos', 04:46:51 INFO - 'log_to_console': True, 04:46:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:46:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:46:51 INFO - 'opt_config_files': (), 04:46:51 INFO - 'pip_index': False, 04:46:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:51 INFO - 'cmd': ('bash', 04:46:51 INFO - '-c', 04:46:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:51 INFO - 'enabled': True, 04:46:51 INFO - 'halt_on_failure': False, 04:46:51 INFO - 'name': 'check_screen_resolution'},), 04:46:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:46:51 INFO - 'cmd': ('bash', 04:46:51 INFO - '-c', 04:46:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:46:51 INFO - 'enabled': True, 04:46:51 INFO - 'halt_on_failure': False, 04:46:51 INFO - 'name': 'check_screen_resolution'},), 04:46:51 INFO - 'run_cmd_checks_enabled': True, 04:46:51 INFO - 'suite': 'dromaeojs', 04:46:51 INFO - 'system_bits': '32', 04:46:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:46:51 INFO - 'title': 't-yosemite-r7-0296', 04:46:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:46:51 INFO - 'use_talos_json': True, 04:46:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:46:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:46:51 INFO - 'work_dir': 'build'} 04:46:51 INFO - [mozharness: 2017-04-18 11:46:51.439583Z] Running clobber step. 04:46:51 INFO - Running pre-action listener: _resource_record_pre_action 04:46:51 INFO - Running main action method: clobber 04:46:51 INFO - rmtree: /builds/slave/test/build 04:46:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:46:53 INFO - Running post-action listener: _resource_record_post_action 04:46:53 INFO - [mozharness: 2017-04-18 11:46:53.269613Z] Finished clobber step (success) 04:46:53 INFO - [mozharness: 2017-04-18 11:46:53.269862Z] Running read-buildbot-config step. 04:46:53 INFO - Running pre-action listener: _resource_record_pre_action 04:46:53 INFO - Running main action method: read_buildbot_config 04:46:53 INFO - Using buildbot properties: 04:46:53 INFO - { 04:46:53 INFO - "project": "", 04:46:53 INFO - "product": "firefox", 04:46:53 INFO - "script_repo_revision": "production", 04:46:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:46:53 INFO - "repository": "", 04:46:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:46:53 INFO - "buildid": "20170418031744", 04:46:53 INFO - "pgo_build": "False", 04:46:53 INFO - "basedir": "/builds/slave/test", 04:46:53 INFO - "buildnumber": 17, 04:46:53 INFO - "slavename": "t-yosemite-r7-0296", 04:46:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:46:53 INFO - "platform": "yosemite_r7", 04:46:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:46:53 INFO - "branch": "mozilla-aurora", 04:46:53 INFO - "repo_path": "releases/mozilla-aurora", 04:46:53 INFO - "revision": "09e97b55acb30be1f8e13b0f637f9cf152658799", 04:46:53 INFO - "stage_platform": "macosx64", 04:46:53 INFO - "builduid": "b98447929eb940aabbbe4a320363fe3f", 04:46:53 INFO - "slavebuilddir": "test" 04:46:53 INFO - } 04:46:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 04:46:53 INFO - Running post-action listener: _resource_record_post_action 04:46:53 INFO - [mozharness: 2017-04-18 11:46:53.277685Z] Finished read-buildbot-config step (success) 04:46:53 INFO - [mozharness: 2017-04-18 11:46:53.278180Z] Running download-and-extract step. 04:46:53 INFO - Running pre-action listener: _resource_record_pre_action 04:46:53 INFO - Running main action method: download_and_extract 04:46:53 INFO - mkdir: /builds/slave/test/build/tests 04:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:53 INFO - trying https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 04:46:53 INFO - Downloading https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/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 04:46:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/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 04:46:54 INFO - Downloaded 1199 bytes. 04:46:54 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 04:46:54 INFO - Using the following test package requirements: 04:46:54 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 04:46:54 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 04:46:54 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 04:46:54 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'jsshell-mac.zip'], 04:46:54 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 04:46:54 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 04:46:54 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 04:46:54 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 04:46:54 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 04:46:54 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:46:54 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 04:46:54 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:46:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 04:46:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:46:54 INFO - Fetch https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 04:46:57 INFO - Expected file size: 51794441 04:46:57 INFO - Obtained file size: 51794441 04:47:13 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 04:47:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 04:47:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:47:13 INFO - Fetch https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 04:47:18 INFO - Expected file size: 51794441 04:47:18 INFO - Obtained file size: 51794441 04:47:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 04:47:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:47:33 INFO - Fetch https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 04:47:34 INFO - Expected file size: 14404424 04:47:34 INFO - Obtained file size: 14404424 04:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:35 INFO - trying https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:47:35 INFO - Downloading https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 04:47:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/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 04:47:37 INFO - Downloaded 74745326 bytes. 04:47:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:47:37 INFO - mkdir: /builds/slave/test/properties 04:47:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:47:37 INFO - Writing to file /builds/slave/test/properties/build_url 04:47:37 INFO - Contents: 04:47:37 INFO - build_url:https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:47:38 INFO - Running post-action listener: _resource_record_post_action 04:47:38 INFO - Running post-action listener: set_extra_try_arguments 04:47:38 INFO - [mozharness: 2017-04-18 11:47:38.665730Z] Finished download-and-extract step (success) 04:47:38 INFO - [mozharness: 2017-04-18 11:47:38.665853Z] Running populate-webroot step. 04:47:38 INFO - Running pre-action listener: _resource_record_pre_action 04:47:38 INFO - Running main action method: populate_webroot 04:47:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:47:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:47:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:47:38 INFO - '--tppagecycles', 04:47:38 INFO - '7'], 04:47:38 INFO - 'tests': ['tsvgm']}}, 04:47:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:47:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:47:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:47:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:47:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:47:38 INFO - 'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:47:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:47:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:47:38 INFO - 'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['damp', 'tps']}, 04:47:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:47:38 INFO - 'tests': ['damp', 'tps']}, 04:47:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['dromaeo_dom']}, 04:47:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:47:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['basic_compositor_video']}, 04:47:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:47:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['a11yr', 04:47:38 INFO - 'ts_paint', 04:47:38 INFO - 'tpaint', 04:47:38 INFO - 'sessionrestore', 04:47:38 INFO - 'sessionrestore_no_auto_restore', 04:47:38 INFO - 'tabpaint']}, 04:47:38 INFO - 'other-e10s': {'tests': ['a11yr', 04:47:38 INFO - 'ts_paint', 04:47:38 INFO - 'tpaint', 04:47:38 INFO - 'sessionrestore', 04:47:38 INFO - 'sessionrestore_no_auto_restore', 04:47:38 INFO - 'tabpaint']}, 04:47:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:47:38 INFO - 'ts_paint', 04:47:38 INFO - 'tpaint', 04:47:38 INFO - 'sessionrestore', 04:47:38 INFO - 'sessionrestore_no_auto_restore', 04:47:38 INFO - 'tabpaint']}, 04:47:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:47:38 INFO - 'ts_paint', 04:47:38 INFO - 'tpaint', 04:47:38 INFO - 'sessionrestore', 04:47:38 INFO - 'sessionrestore_no_auto_restore', 04:47:38 INFO - 'tabpaint']}, 04:47:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['a11yr', 04:47:38 INFO - 'ts_paint', 04:47:38 INFO - 'tpaint', 04:47:38 INFO - 'sessionrestore', 04:47:38 INFO - 'sessionrestore_no_auto_restore', 04:47:38 INFO - 'tabpaint']}, 04:47:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['a11yr', 04:47:38 INFO - 'ts_paint', 04:47:38 INFO - 'tpaint', 04:47:38 INFO - 'sessionrestore', 04:47:38 INFO - 'sessionrestore_no_auto_restore', 04:47:38 INFO - 'tabpaint']}, 04:47:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['tsvgx', 04:47:38 INFO - 'tsvgr_opacity', 04:47:38 INFO - 'tart', 04:47:38 INFO - 'tscrollx', 04:47:38 INFO - 'cart', 04:47:38 INFO - 'tsvg_static']}, 04:47:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:47:38 INFO - 'tsvgr_opacity', 04:47:38 INFO - 'tart', 04:47:38 INFO - 'tscrollx', 04:47:38 INFO - 'cart', 04:47:38 INFO - 'tsvg_static']}, 04:47:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:47:38 INFO - 'talos_options': ['--disable-e10s'], 04:47:38 INFO - 'tests': ['tp5o']}, 04:47:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:47:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:47:38 INFO - 'talos_options': ['--disable-e10s', 04:47:38 INFO - '--xperf_path', 04:47:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:38 INFO - 'tests': ['tp5n']}, 04:47:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:47:38 INFO - 'talos_options': ['--xperf_path', 04:47:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:47:38 INFO - 'tests': ['tp5n']}}, 04:47:38 INFO - 'talos.zip': {'path': '', 04:47:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:47:38 INFO - Running post-action listener: _resource_record_post_action 04:47:38 INFO - [mozharness: 2017-04-18 11:47:38.684513Z] Finished populate-webroot step (success) 04:47:38 INFO - [mozharness: 2017-04-18 11:47:38.684646Z] Running create-virtualenv step. 04:47:38 INFO - Running pre-action listener: _resource_record_pre_action 04:47:38 INFO - Running main action method: create_virtualenv 04:47:38 INFO - Creating virtualenv /builds/slave/test/build/venv 04:47:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:47:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:47:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:47:38 INFO - Using real prefix '/tools/python27' 04:47:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:47:39 INFO - Installing distribute.............................................................................................................................................................................................done. 04:47:42 INFO - Installing pip.................done. 04:47:42 INFO - Return code: 0 04:47:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:47:42 INFO - Reading from file tmpfile_stdout 04:47:42 INFO - Output received: 04:47:42 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:47:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:42 INFO - 'HOME': '/Users/cltbld', 04:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:42 INFO - 'LOGNAME': 'cltbld', 04:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:42 INFO - 'MOZ_NO_REMOTE': '1', 04:47:42 INFO - 'NO_EM_RESTART': '1', 04:47:42 INFO - 'PAGER': '/bin/cat', 04:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:42 INFO - 'PWD': '/builds/slave/test', 04:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:42 INFO - 'SHELL': '/bin/bash', 04:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:47:42 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:42 INFO - 'USER': 'cltbld', 04:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:42 INFO - 'XPC_FLAGS': '0x0', 04:47:42 INFO - 'XPC_SERVICE_NAME': '0', 04:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:47:43 INFO - Cleaning up... 04:47:43 INFO - Return code: 0 04:47:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:43 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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:43 INFO - 'HOME': '/Users/cltbld', 04:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:43 INFO - 'LOGNAME': 'cltbld', 04:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:43 INFO - 'MOZ_NO_REMOTE': '1', 04:47:43 INFO - 'NO_EM_RESTART': '1', 04:47:43 INFO - 'PAGER': '/bin/cat', 04:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:43 INFO - 'PWD': '/builds/slave/test', 04:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:43 INFO - 'SHELL': '/bin/bash', 04:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:47:43 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:43 INFO - 'USER': 'cltbld', 04:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:43 INFO - 'XPC_FLAGS': '0x0', 04:47:43 INFO - 'XPC_SERVICE_NAME': '0', 04:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:43 INFO - Downloading/unpacking psutil>=3.1.1 04:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:47:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:48 INFO - Installing collected packages: psutil 04:47:48 INFO - Running setup.py install for psutil 04:47:48 INFO - building 'psutil._psutil_osx' extension 04:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:47:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:47:48 INFO - building 'psutil._psutil_posix' extension 04:47:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:47:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:47:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:47:48 INFO - ^ 04:47:48 INFO - 1 warning generated. 04:47:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:47:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:47:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:47:48 INFO - Successfully installed psutil 04:47:48 INFO - Cleaning up... 04:47:48 INFO - Return code: 0 04:47:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:48 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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:47:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:47:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:47:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:48 INFO - 'HOME': '/Users/cltbld', 04:47:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:48 INFO - 'LOGNAME': 'cltbld', 04:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:48 INFO - 'MOZ_NO_REMOTE': '1', 04:47:48 INFO - 'NO_EM_RESTART': '1', 04:47:48 INFO - 'PAGER': '/bin/cat', 04:47:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:48 INFO - 'PWD': '/builds/slave/test', 04:47:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:48 INFO - 'SHELL': '/bin/bash', 04:47:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:47:48 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:47:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:48 INFO - 'USER': 'cltbld', 04:47:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:48 INFO - 'XPC_FLAGS': '0x0', 04:47:48 INFO - 'XPC_SERVICE_NAME': '0', 04:47:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:47:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:47:51 INFO - Installing collected packages: mozsystemmonitor 04:47:51 INFO - Running setup.py install for mozsystemmonitor 04:47:52 INFO - Successfully installed mozsystemmonitor 04:47:52 INFO - Cleaning up... 04:47:52 INFO - Return code: 0 04:47:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:52 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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:52 INFO - 'HOME': '/Users/cltbld', 04:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:52 INFO - 'LOGNAME': 'cltbld', 04:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:52 INFO - 'MOZ_NO_REMOTE': '1', 04:47:52 INFO - 'NO_EM_RESTART': '1', 04:47:52 INFO - 'PAGER': '/bin/cat', 04:47:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:52 INFO - 'PWD': '/builds/slave/test', 04:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:52 INFO - 'SHELL': '/bin/bash', 04:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:47:52 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:52 INFO - 'USER': 'cltbld', 04:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:52 INFO - 'XPC_FLAGS': '0x0', 04:47:52 INFO - 'XPC_SERVICE_NAME': '0', 04:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:52 INFO - Downloading/unpacking jsonschema==2.5.1 04:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:47:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:55 INFO - Downloading functools32-3.2.3-2.tar.gz 04:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:47:55 INFO - warning: no files found matching '*.txt' 04:47:55 INFO - no previously-included directories found matching 'build' 04:47:55 INFO - no previously-included directories found matching 'dist' 04:47:55 INFO - no previously-included directories found matching '.git*' 04:47:55 INFO - Installing collected packages: jsonschema, functools32 04:47:55 INFO - Running setup.py install for functools32 04:47:55 INFO - warning: no files found matching '*.txt' 04:47:55 INFO - no previously-included directories found matching 'build' 04:47:55 INFO - no previously-included directories found matching 'dist' 04:47:55 INFO - no previously-included directories found matching '.git*' 04:47:55 INFO - Successfully installed jsonschema functools32 04:47:55 INFO - Cleaning up... 04:47:55 INFO - Return code: 0 04:47:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47:55 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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:55 INFO - 'HOME': '/Users/cltbld', 04:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:55 INFO - 'LOGNAME': 'cltbld', 04:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:55 INFO - 'MOZ_NO_REMOTE': '1', 04:47:55 INFO - 'NO_EM_RESTART': '1', 04:47:55 INFO - 'PAGER': '/bin/cat', 04:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:55 INFO - 'PWD': '/builds/slave/test', 04:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:55 INFO - 'SHELL': '/bin/bash', 04:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:47:55 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:55 INFO - 'USER': 'cltbld', 04:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:55 INFO - 'XPC_FLAGS': '0x0', 04:47:55 INFO - 'XPC_SERVICE_NAME': '0', 04:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:47:56 INFO - Cleaning up... 04:47:56 INFO - Return code: 0 04:47:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:47: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')]} 04:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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')]} 04:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:47: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', '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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:47: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:47: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 blobuploader==1.2.4 04:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:56 INFO - 'HOME': '/Users/cltbld', 04:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:56 INFO - 'LOGNAME': 'cltbld', 04:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:56 INFO - 'MOZ_NO_REMOTE': '1', 04:47:56 INFO - 'NO_EM_RESTART': '1', 04:47:56 INFO - 'PAGER': '/bin/cat', 04:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:56 INFO - 'PWD': '/builds/slave/test', 04:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:56 INFO - 'SHELL': '/bin/bash', 04:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:47:56 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:56 INFO - 'USER': 'cltbld', 04:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:56 INFO - 'XPC_FLAGS': '0x0', 04:47:56 INFO - 'XPC_SERVICE_NAME': '0', 04:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:47:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47: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 04:47:59 INFO - Downloading blobuploader-1.2.4.tar.gz 04:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:47:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:48:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:00 INFO - Downloading docopt-0.6.1.tar.gz 04:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:48:00 INFO - Installing collected packages: blobuploader, requests, docopt 04:48:00 INFO - Running setup.py install for blobuploader 04:48:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:48:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:48:00 INFO - Running setup.py install for requests 04:48:01 INFO - Running setup.py install for docopt 04:48:01 INFO - Successfully installed blobuploader requests docopt 04:48:01 INFO - Cleaning up... 04:48:01 INFO - Return code: 0 04:48:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:01 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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:01 INFO - 'HOME': '/Users/cltbld', 04:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:01 INFO - 'LOGNAME': 'cltbld', 04:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:01 INFO - 'MOZ_NO_REMOTE': '1', 04:48:01 INFO - 'NO_EM_RESTART': '1', 04:48:01 INFO - 'PAGER': '/bin/cat', 04:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:01 INFO - 'PWD': '/builds/slave/test', 04:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:01 INFO - 'SHELL': '/bin/bash', 04:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:48:01 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:01 INFO - 'USER': 'cltbld', 04:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:01 INFO - 'XPC_FLAGS': '0x0', 04:48:01 INFO - 'XPC_SERVICE_NAME': '0', 04:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:01 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-PXLGhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-OpVDNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-RuDRmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-pDChOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-kYZ8Wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-EGfQ6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Uqdwqw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-u0wBBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KB23mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:02 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-1_hl9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-rrh3he-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-DFIUkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-z4GorL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Q2q5H0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wmlcmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-PtK2a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:03 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-kHfZud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:48:03 INFO - Running setup.py install for manifestparser 04:48:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:48:03 INFO - Running setup.py install for mozcrash 04:48:04 INFO - Running setup.py install for mozdebug 04:48:04 INFO - Running setup.py install for mozdevice 04:48:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Running setup.py install for mozfile 04:48:04 INFO - Running setup.py install for mozhttpd 04:48:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Running setup.py install for mozinfo 04:48:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Running setup.py install for mozInstall 04:48:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:48:04 INFO - Running setup.py install for mozleak 04:48:05 INFO - Running setup.py install for mozlog 04:48:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:48:05 INFO - Running setup.py install for moznetwork 04:48:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:48:05 INFO - Running setup.py install for mozprocess 04:48:05 INFO - Running setup.py install for mozprofile 04:48:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:48:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:48:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:48:05 INFO - Running setup.py install for mozrunner 04:48:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:48:05 INFO - Running setup.py install for mozscreenshot 04:48:06 INFO - Running setup.py install for moztest 04:48:06 INFO - Running setup.py install for mozversion 04:48:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:48:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:48:06 INFO - Cleaning up... 04:48:06 INFO - Return code: 0 04:48:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:06 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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:06 INFO - 'HOME': '/Users/cltbld', 04:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:06 INFO - 'LOGNAME': 'cltbld', 04:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:06 INFO - 'MOZ_NO_REMOTE': '1', 04:48:06 INFO - 'NO_EM_RESTART': '1', 04:48:06 INFO - 'PAGER': '/bin/cat', 04:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:06 INFO - 'PWD': '/builds/slave/test', 04:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:06 INFO - 'SHELL': '/bin/bash', 04:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:48:06 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:06 INFO - 'USER': 'cltbld', 04:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:06 INFO - 'XPC_FLAGS': '0x0', 04:48:06 INFO - 'XPC_SERVICE_NAME': '0', 04:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:06 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wr0t1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:06 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-uyIwBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:06 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nw3sNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:06 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-nh0Xj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-WHU95K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-EFVoVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Pd68jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-EbfUSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-P_gHbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Rt_8ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-QhqDIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-_JKIS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:07 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)) 04:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:07 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-fjvehF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-TdGE2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-C1X4Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-_gWk3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:08 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-zUGv89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:48:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:12 INFO - Downloading blessings-1.6.tar.gz 04:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:48:12 INFO - Installing collected packages: blessings 04:48:12 INFO - Running setup.py install for blessings 04:48:12 INFO - Successfully installed blessings 04:48:12 INFO - Cleaning up... 04:48:12 INFO - Return code: 0 04:48:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:48:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:12 INFO - Reading from file tmpfile_stdout 04:48:12 INFO - Current package versions: 04:48:12 INFO - blessings == 1.6 04:48:12 INFO - blobuploader == 1.2.4 04:48:12 INFO - docopt == 0.6.1 04:48:12 INFO - functools32 == 3.2.3-2 04:48:12 INFO - jsonschema == 2.5.1 04:48:12 INFO - manifestparser == 1.1 04:48:12 INFO - mozInstall == 1.12 04:48:12 INFO - mozcrash == 1.0 04:48:12 INFO - mozdebug == 0.1 04:48:12 INFO - mozdevice == 0.49 04:48:12 INFO - mozfile == 1.2 04:48:12 INFO - mozhttpd == 0.7 04:48:12 INFO - mozinfo == 0.9 04:48:12 INFO - mozleak == 0.1 04:48:12 INFO - mozlog == 3.4 04:48:12 INFO - moznetwork == 0.27 04:48:12 INFO - mozprocess == 0.25 04:48:12 INFO - mozprofile == 0.28 04:48:12 INFO - mozrunner == 6.13 04:48:12 INFO - mozscreenshot == 0.1 04:48:12 INFO - mozsystemmonitor == 0.3 04:48:12 INFO - moztest == 0.8 04:48:12 INFO - mozversion == 1.4 04:48:12 INFO - psutil == 3.1.1 04:48:12 INFO - requests == 1.2.3 04:48:12 INFO - wsgiref == 0.1.2 04:48:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:12 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 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:12 INFO - 'HOME': '/Users/cltbld', 04:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:12 INFO - 'LOGNAME': 'cltbld', 04:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:12 INFO - 'MOZ_NO_REMOTE': '1', 04:48:12 INFO - 'NO_EM_RESTART': '1', 04:48:12 INFO - 'PAGER': '/bin/cat', 04:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:12 INFO - 'PWD': '/builds/slave/test', 04:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:12 INFO - 'SHELL': '/bin/bash', 04:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:48:12 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:12 INFO - 'USER': 'cltbld', 04:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:12 INFO - 'XPC_FLAGS': '0x0', 04:48:12 INFO - 'XPC_SERVICE_NAME': '0', 04:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:48:13 INFO - Cleaning up... 04:48:13 INFO - Return code: 0 04:48:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102102768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f944158a3e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, '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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:48:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:13 INFO - 'HOME': '/Users/cltbld', 04:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:13 INFO - 'LOGNAME': 'cltbld', 04:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:13 INFO - 'MOZ_NO_REMOTE': '1', 04:48:13 INFO - 'NO_EM_RESTART': '1', 04:48:13 INFO - 'PAGER': '/bin/cat', 04:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:13 INFO - 'PWD': '/builds/slave/test', 04:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:13 INFO - 'SHELL': '/bin/bash', 04:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:48:13 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:13 INFO - 'USER': 'cltbld', 04:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:13 INFO - 'XPC_FLAGS': '0x0', 04:48:13 INFO - 'XPC_SERVICE_NAME': '0', 04:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:48:13 INFO - Cleaning up... 04:48:13 INFO - Return code: 0 04:48:13 INFO - Running post-action listener: _resource_record_post_action 04:48:13 INFO - Running post-action listener: _start_resource_monitoring 04:48:13 INFO - Starting resource monitoring. 04:48:13 INFO - [mozharness: 2017-04-18 11:48:13.504008Z] Finished create-virtualenv step (success) 04:48:13 INFO - [mozharness: 2017-04-18 11:48:13.504982Z] Running install step. 04:48:13 INFO - Running pre-action listener: _resource_record_pre_action 04:48:13 INFO - Running main action method: install 04:48:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:13 INFO - Reading from file tmpfile_stdout 04:48:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:13 INFO - Reading from file tmpfile_stdout 04:48:13 INFO - Output received: 04:48:13 INFO - Usage: mozinstall [options] installer 04:48:13 INFO - Options: 04:48:13 INFO - -h, --help show this help message and exit 04:48:13 INFO - -d DEST, --destination=DEST 04:48:13 INFO - Directory to install application into. [default: 04:48:13 INFO - "/builds/slave/test"] 04:48:13 INFO - --app=APP Application being installed. [default: firefox] 04:48:13 INFO - mkdir: /builds/slave/test/build/application 04:48:13 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'] 04:48:13 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 04:48:34 INFO - Reading from file tmpfile_stdout 04:48:34 INFO - Output received: 04:48:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:48:34 INFO - Running post-action listener: _resource_record_post_action 04:48:34 INFO - [mozharness: 2017-04-18 11:48:34.129765Z] Finished install step (success) 04:48:34 INFO - [mozharness: 2017-04-18 11:48:34.129902Z] Running run-tests step. 04:48:34 INFO - Running pre-action listener: _resource_record_pre_action 04:48:34 INFO - Running main action method: run_tests 04:48:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:34 INFO - 2017-04-18 04:48:34.144 screenresolution[2295:27175] starting screenresolution argv=screenresolution get 04:48:34 INFO - 2017-04-18 04:48:34.147 screenresolution[2295:27175] Display 0: 1600x1200x32@60 04:48:34 INFO - 2017-04-18 04:48:34.153 screenresolution[2296:27177] starting screenresolution argv=screenresolution list 04:48:34 INFO - Available Modes on Display 0 04:48:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:34 INFO - Intel Iris: 04:48:34 INFO - Chipset Model: Intel Iris 04:48:34 INFO - Type: GPU 04:48:34 INFO - Bus: Built-In 04:48:34 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:34 INFO - Vendor: Intel (0x8086) 04:48:34 INFO - Device ID: 0x0a2e 04:48:34 INFO - Revision ID: 0x0009 04:48:34 INFO - Displays: 04:48:34 INFO - Display: 04:48:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:34 INFO - Display Serial Number: 1600x1200 60 04:48:34 INFO - Main Display: Yes 04:48:34 INFO - Mirror: Off 04:48:34 INFO - Online: Yes 04:48:34 INFO - Rotation: Supported 04:48:34 INFO - Return code: 0 04:48:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:48:34 INFO - Python 2.7.3 04:48:34 INFO - Return code: 0 04:48:34 INFO - grabbing minidump binary from tooltool 04:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:48:34 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 0x101eddd30>, '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 0x101e04db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ed2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:48:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:48:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:48:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:48:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:48:34 INFO - Return code: 0 04:48:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:48:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:48:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:48:34 INFO - ENV: RUST_BACKTRACE is now 1 04:48:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:48:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:48:34 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/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:48:34 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/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JOcjDYc2TK/Render', 04:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:34 INFO - 'HOME': '/Users/cltbld', 04:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:34 INFO - 'LOGNAME': 'cltbld', 04:48:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:48:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:34 INFO - 'MOZ_NO_REMOTE': '1', 04:48:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:48:34 INFO - 'NO_EM_RESTART': '1', 04:48:34 INFO - 'PAGER': '/bin/cat', 04:48:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:34 INFO - 'PWD': '/builds/slave/test', 04:48:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:34 INFO - 'RUST_BACKTRACE': '1', 04:48:34 INFO - 'SHELL': '/bin/bash', 04:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ofn5TAAip4/Listeners', 04:48:34 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:34 INFO - 'USER': 'cltbld', 04:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:34 INFO - 'XPC_FLAGS': '0x0', 04:48:34 INFO - 'XPC_SERVICE_NAME': '0', 04:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:34 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/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:48:34 INFO - mozversion application_buildid: 20170418031744 04:48:34 INFO - mozversion application_changeset: 09e97b55acb30be1f8e13b0f637f9cf152658799 04:48:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:48:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:48:34 INFO - mozversion application_name: Firefox 04:48:34 INFO - mozversion application_remotingname: firefox-dev 04:48:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:48:34 INFO - mozversion application_vendor: Mozilla 04:48:34 INFO - mozversion application_version: 54.0a2 04:48:34 INFO - mozversion platform_buildid: 20170418031744 04:48:34 INFO - mozversion platform_changeset: 09e97b55acb30be1f8e13b0f637f9cf152658799 04:48:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:48:34 INFO - mozversion platform_version: 54.0a2 04:48:34 INFO - using testdate: 1492516114 04:48:34 INFO - actual date: 1492516114 04:48:34 INFO - starting webserver on 'localhost:56107' 04:48:34 INFO - SUITE-START | Running 2 tests 04:48:34 INFO - TEST-START | dromaeo_css 04:48:34 INFO - operating with platform_type : mac_ 04:48:34 INFO - Initialising browser for dromaeo_css test... 04:48:34 INFO - TEST-INFO | started process 2306 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp9uS7L7/profile http://localhost:56107/getInfo.html) 04:48:37 INFO - PROCESS | 2306 | __metrics Screen width/height:1600/1200 04:48:37 INFO - PROCESS | 2306 | colorDepth:24 04:48:37 INFO - PROCESS | 2306 | Browser inner width/height: 1024/689 04:48:37 INFO - PROCESS | 2306 | __metrics 04:48:37 INFO - PROCESS | 2306 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:48:37 INFO - PROCESS | 2306 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:48:37 INFO - TEST-INFO | 2306: exit 0 04:48:37 INFO - Browser initialized. 04:48:37 INFO - Running cycle 1/1 for dromaeo_css test... 04:48:37 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmp9uS7L7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:48:50 INFO - PROCESS | 2308 | RSS: Main: 214962176 04:48:50 INFO - PROCESS | 2308 | 04:51:53 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:56107/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56107/tests/dromaeo/cssquery-ext.html) 04:51:56 INFO - PROCESS | 2308 | RSS: Main: 431341568 04:51:56 INFO - PROCESS | 2308 | 04:53:39 INFO - PROCESS | 2308 | ************************* 04:53:39 INFO - PROCESS | 2308 | A coding exception was thrown and uncaught in a Task. 04:53:39 INFO - PROCESS | 2308 | 04:53:39 ERROR - PROCESS | 2308 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:39 INFO - PROCESS | 2308 | Full stack: 04:53:39 INFO - PROCESS | 2308 | ************************* 04:53:39 INFO - PROCESS | 2308 | ************************* 04:53:39 INFO - PROCESS | 2308 | A coding exception was thrown and uncaught in a Task. 04:53:39 INFO - PROCESS | 2308 | 04:53:39 ERROR - PROCESS | 2308 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:39 INFO - PROCESS | 2308 | Full stack: 04:53:39 INFO - PROCESS | 2308 | ************************* 04:53:39 INFO - PROCESS | 2308 | ************************* 04:53:39 INFO - PROCESS | 2308 | A coding exception was thrown and uncaught in a Task. 04:53:39 INFO - PROCESS | 2308 | 04:53:39 ERROR - PROCESS | 2308 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:39 INFO - PROCESS | 2308 | Full stack: 04:53:39 INFO - PROCESS | 2308 | ************************* 04:53:39 INFO - PROCESS | 2308 | ************************* 04:53:39 INFO - PROCESS | 2308 | A coding exception was thrown and uncaught in a Task. 04:53:39 INFO - PROCESS | 2308 | 04:53:39 ERROR - PROCESS | 2308 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:53:39 INFO - PROCESS | 2308 | Full stack: 04:53:39 INFO - PROCESS | 2308 | ************************* 04:54:59 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:56107/tests/dromaeo/cssquery-ext.html (next: http://localhost:56107/tests/dromaeo/cssquery-jquery.html) 04:55:00 INFO - PROCESS | 2308 | RSS: Main: 352194560 04:55:00 INFO - PROCESS | 2308 | 04:58:02 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:56107/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56107/tests/dromaeo/cssquery-mootools.html) 04:58:03 INFO - PROCESS | 2308 | RSS: Main: 339906560 04:58:03 INFO - PROCESS | 2308 | 05:01:05 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:56107/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56107/tests/dromaeo/cssquery-prototype.html) 05:01:06 INFO - PROCESS | 2308 | RSS: Main: 309833728 05:01:06 INFO - PROCESS | 2308 | 05:04:10 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:56107/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56107/tests/dromaeo/cssquery-yui.html) 05:04:10 INFO - PROCESS | 2308 | RSS: Main: 315543552 05:04:10 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:56107/tests/dromaeo/cssquery-yui.html (next: http://localhost:56107/tests/dromaeo/cssquery-dojo.html) 05:07:14 INFO - PROCESS | 2308 | RSS: Main: 494260224 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | __start_tp_report 05:07:14 INFO - PROCESS | 2308 | _x_x_mozilla_page_load 05:07:14 INFO - PROCESS | 2308 | _x_x_mozilla_page_load_details 05:07:14 INFO - PROCESS | 2308 | |i|pagename|runs| 05:07:14 INFO - PROCESS | 2308 | |0;dojo.html;3359.344927739092;3375.831208439578;3378.155461134717;3386.3058073095017;3414.5048967899656;5833.416658334165;5856.648601083938;5922.082077278029;5944.375840536746;5960.910586341203;5859.472647461738;5941.0494320908665;5943.494802941756;5943.910841337383;5959.672218028018;5275.340582427197;5318.680879147263;5336.439673834249;5388.488093631099;5453.100238460649;5243.4232234454175;5270.420253772101;5324.281222035033;5463.9180412293845;5469.863253418656;5351.892962140755;5376.892462150755;5397.730113494329;5401;5462.262594549725;5406.189071639246;5408.567314614841;5408.756605952742;5410.675359478405;5439.374471935718;5160.303359046518;5274.024305503494;5330.706811125387;5346.679199248058;5414.91877621836;5732.140178973145;5746.166805813175;5764.221830052931;5776.133579963039;5788.305403351583;6228.37716228378;6265.624062556261;6320.304766475684;6334.619922804646;6355.872882542323;6249.250089989185;6306.49548036156;6328.778492752777;6335.778247761306;6347.238331400215;5982.162497250389;6054.212952316215;6087.908681369828;6091.817245482643;6097.085437184369;6215.906761398583;6263;6290.402411770874;6294.590851594632;6433.324500927429;6194.5973511721595;6229.065640153923;6236.126942228092;6315.336889626615;6358.586691865014;5394.595405344615;6262.467690246289;6323.083152942798;6330.8100756977365;6352.841178970562;5376;5401.4868587484125;6267;6293.087502312139;6307.432331090224;6208.782692605737;6240.095186197976;6243.157173781572;6247.969085100907;6271.62370257786;5126.231065340229;5137.614678899097;5137.691738495702;5144.511271429208;5179.896402072013;4541.296099104644;4597.632189424838;4599.9080018399445;4611.262198048296;4627.005193883264;5673.205751194836;5734.139879018181;5783.624064435799;5884.176215329857;5911.674857882772;6254.781082662176;6261.24865016201;6330.208723909512;6337.366263373718;6371.840703982438;5865.061590145556;5898.911516327258;5900.586958912921;5900.76396944126;5950.761969521257;5360.8659783504945;5616.438356164351;5616.494515493625;5617.915731264035;5617.9719101404235;6311.1795466589965;6316.463100636497;6373.139626150596;6418;6449.097126402401;6310.936890631218;6322.968385158045;6325.4939604832425;6325.588836725598;6326.778562750282;6490.156279683611;6512.218533775977;6515.153030106056;6515.73937042513;6534.346565343428;6149.323574406902;6175.382461753788;6255.624662520263;6262.5929314594405;6266.216722909637;6240.438360547572;6288.025356069634;6338.207724034496;6345.428911397997;6358.237011558643;6222.0355844844125;6287.874242515032;6304.464120549805;6318.936810632019;6349.206349206349;6315.052742088724;6332.240131184288;6340.397662222082;6355.682215889095;6360.618362898241;5958.582899197015;5987.401259873977;6001.939980600313;6008.699565021644;6011.338752737282;6179.876402472015;6193.845153871009;6233.2208473940755;6326.620402775849;6334.271558770616;6120.969395152996;6130.550643787619;6143.754249830135;6158.168647232566;6165 05:07:14 INFO - PROCESS | 2308 | |1;ext.html;717.9246179151189;788.4914230321444;858.6436628799049;864.7492227254097;873.5719497446249;27169;38411.807940960294;39339.40990885132;41264.58735412645;41402.58597414025;40437.191256174934;40567.797161014154;40639.98400040001;40770;40837.7958110209;7330.706771729151;8880.733577992654;9059;9079.0920907909;9095.408303546887;7337.119545654529;10959.01368876801;11216.49525771338;11268.042216411615;11286.379249141293;11087.944560277187;11132.944335278353;11135.387553684544;11148.888511114867;11216.66350009497;11073.169512286571;11103.55585776568;11125;11126.38804865732;11150.99641032311;7249.456290778213;7447.404207663374;7458.216887226871;7459.738909138154;7459.850802983964;38238.617613823786;38806.61193388058;38823.41764873529;38824.22351552981;38855;3186.9043928682177;3192.201949512622;3197.392495425862;3238.239013831748;3321.4021476134285;26582.06962756294;26933.32666683329;26980.595291070455;28786.568201476995;28882.566761498594;3005;3136.498160294342;3141.1361875484195;3260.6413294537583;3267.918302042468;306239;308190;308395.9160408367;308618;309411;42133.73598792041;42973.57026429758;48329.85855919425;51233;51240.487595124316;52478.68803279949;52905.7354713224;54972.900541988936;55065.72467137639;55241;14021.649458763408;14206.289685515889;14212;14217.365003024577;14267.92866035684;11750.412479375997;12007.339596322287;12029.639110826689;12086.576969806014;12123.757524849454;13235.007374446763;13260.602181934557;13288.402021909038;13327.73344533104;13402.463901444027;5749.88500229993;5975.641461512323;6011.579189456784;6019.157317975487;6027.6986150693165;930.8138372325562;950.7147855643279;952.7570469530225;959.8560215967522;986.8273052215977;20510.79489205089;26392.8680356597;27648.170554882985;27711.307217319325;28144.8592757039;1019.8572199891872;1046.6179844356755;1047.225053460449;1051.7107795356337;1065.6962765611763;11519.942400287944;11711;11815.350155741346;13162.868371316164;13880.4447822088;448969;452803;453637;455059;455400;56416;56613;56853.71573142108;57002.42997569971;57083.71458142683;1428.2073449235775;1456.992715036418;1457.2640816387868;1458.9124652520882;1460.1531111955253;26416.339591509597;26536;27452.209027073706;27744.167674969784;27810;45867.62397128092;45909;46095;46108;46142.76928615336;393209;393352;393353;394081;394118;27499.45001099927;32825.851095211554;35239.647603523634;37688.43467348047;38455;45109.77445112754;45294.54705452903;45442.54557454383;45814.85462863322;46519.534804652874;30248;30805;30807.075787726404;31344.21639459032;31888;24200.515989679756;31050.844745776125;31614.841925791145;31633.780426229416;31684;36651.816740916125;37651.81174094112;37780.811095944344;37822;37874.810625946695;29782.404351913272;30120.84939575288;31013.224669383446;32035.679643203268;32092.03723888287;22885.199018034287;23820.880895595412;24457.51084978255;24952.251432457055;25307 05:07:14 INFO - PROCESS | 2308 | |2;jquery.html;382.6871162004211;411.7076875418455;425.472414206384;433.68557795598184;448.20667418668955;14150.787738183923;20292.89853550734;20450.795492045076;20658.17367305306;20893.164273429084;21275.893620531875;22194.334169974885;22345.888270558626;22407.32778016658;22707.86460676968;5227.745341118123;6384.712687929043;6636.800895973129;6652.733890644368;6658.700358483879;6524.836879078037;6586.93413065868;6622.503312251576;6638.435732962704;6663.267040625527;6362.427381535661;6597.307282735292;6648.9667551662415;6670.899936500957;6692.096566963446;5513.421090785449;6617.602943823362;6657.966710166443;6666.266710661824;6680.131582894217;7183.138023437169;7243.818904527377;7258.927410725878;7268.600226987513;7270.236625154335;24927.875360623264;25821;25943.870280648665;26078.087266945757;26418.6037209442;2801.0196431249105;2874.425114976992;2882.206138013154;2892.291388609804;2957.31981644223;13728.588142355746;15622.843771562253;15812;15862.320615418479;16659.500214993568;1942.660034493958;2031.3296612117965;2033.278186243877;2047.1606641277006;2054.137262349817;124778.37610811979;126606;129690.7030929688;130392;132783.99999999904;13743.862561374259;14144.09988150633;16260.512184634243;16423.425180118877;17842.732359014626;27781;28279;28398.148055558366;28845;28910.566341504895;8126.75619731409;8214.671413143407;8735.475871447734;8842.955785221162;9864.704058878102;7159.713611455588;8131.064927533409;8156.918430815735;8160.224778646019;8290.709825156091;8933.374663773604;8948.552572371356;9099.408538445112;9101.226395756436;9123.270138388913;3380.695737383647;3602.6402706227464;3725.8509659613856;3884.5532763731967;3948.2498325318097;842.8693552499373;904.3619830444568;928.3594319919234;952.6903756279237;1031.566741968375;19780.30768923081;20687.965601719854;21277.46806329854;23227.419314517272;23555.41111472227;915.7344370132587;920.9493477858782;926.5400362038798;945.4658118163215;946.4274114160971;6928.861422771516;6957.79126626202;7032.542884712478;7137.464690148156;7152.177499587614;777606;779256;795180;797972;803573;158532.20733896256;159946.4005359955;165995.3400466004;166331.336686634;166735.1663241676;1225.148521777356;1247.731737676404;1276.2661469654986;1284.1845428153003;1285.1839082182694;12440.564580239648;12598.685032873886;12719.55481558141;12771.80842287385;12773.61679149627;51391.74304128456;54578;57418.7129064352;57512;58262.4173758257;60912;77413.61293193499;82595.76106358289;91284;91696.16607667942;10967.945160274148;11052.889471105507;11268.718282043015;11477.942610286895;11705.88294117082;10890;12829;12919.483220671067;12965.481380744664;13010.284434356201;11179.776404472028;11258.986691197832;11266.830997535208;11345.319280843176;11381.146414018816;9114.36199466031;9672;9729.416235025637;10460.529276182438;10576.629817956335;10124.139448146696;11385.146114041645;11501.367424791717;11681.299122052704;11751.529938802352;9302.441853488812;9651.469169195761;10969.286996345212;11268.04221641137;11425.257358271685;10834;11107.611233606785;11330.036946859609;11355.432228388383;11523.078153747512 05:07:14 INFO - PROCESS | 2308 | |3;mootools.html;1537.4849425442476;1559.9922000389997;1582.1456413536691;1596.6168119651288;1597.1774536113899;10621.628243011497;11167.83248251277;11177.888221117786;11178.329300241981;11201.551937922473;10725.839112413301;10873.619423320186;11096.722581935455;11179.944100279487;11214.887851121486;1974;2389.354874183969;2390.6653068570413;2391.7966972807244;2397.5324811661735;2303.447172678562;2361.5749165150264;2392.342105920868;2392.461696118377;2395.688560487134;2303.9424014399606;2351.2828587280874;2352.0826877517784;2391.2945681024053;2409.8072154227707;2331.4870728439714;2335.1593426366494;2357.186770564161;2359.115331750585;2365.7279412867397;2354.7292061412986;2354.9882250588807;2355.4111472131967;2360.091364824538;2360.6695062691238;10945.069669078059;10965.780684386267;11007.504662290215;11011.449427528672;11016.339019658846;5299.33758280215;5631.465010823965;5636.520895723827;5641.238432811559;5646.378898321223;3226.0966929259844;3270.689284517967;3453.5510383650217;3501.1772233525103;3543.255916257563;1075.688050465364;1127.239113598326;1136.2443974756777;1177.7350096228365;1182.24927171246;12834.486620535168;13650.044496885223;13974.091684040504;14280.357383917644;15235.466758663393;4078.530968938551;4198.958010419857;4224.957750422518;4438.134563760036;4586.334981427741;11496.540138394537;11544.191906566628;11559.711007224887;11574;11582.189246752814;5535.944640553624;5536.390996990328;5544.085225937756;5552.639078459887;5553.222548843165;5011;5185.170372740419;5219.347581552306;5224.03355379256;5257.211418287287;3612.313660404515;3744.2137151198554;3762.9247415051545;3804.2581696568905;3817.7900215488417;870.3385427075342;954.0710020237942;958.5542722634009;968.3221744778683;971.5482300730195;909.5179554835947;917.8623206518942;930.1256818590504;942.7030485397078;979.5102448775613;5984.3417224105315;6379.71291291893;6385.582489598017;6570.540062195696;6633.800985970429;803.0764620686141;973.5424350555228;976.3653625143713;999.0758548342753;1006.5420233793607;3871.399933010388;4075.97962010194;4108.137291168888;4136.462259906193;4153.68847336451;13208.207507549578;13846.376913038735;13879.583612491642;13918.58244252674;13970.720585588231;11751.353675547756;12730.981521478445;13134.474621015052;13708.52020179289;13784.724305513833;9867.40795552269;10140.188784897191;10161.98380161978;10163.136133428741;10167.593296268215;6603.306652801387;6649.933500664932;6661.067450556829;6818.386345228953;6831.351021652935;10392.532336044746;11763.588274410355;12145.757084858431;12262.938685306517;12345.506179753069;11348.092152627934;11399.715007124889;11726.824097638713;12552;12558;8806.163414475615;9153.63385464574;9437.528123593931;9494.145526902612;9536.809263814823;10326.380417174994;10335.534900929326;10348.96510348959;10443.68668939933;10479.266451348332;8823.602937867683;8854.734357969272;8869.866951995597;8919.331050171393;8919.866202006846;8516.744497664831;8618.956905215433;8699.91300086991;8721.127887211482;8730.476171429735;7882.369410447265;7916.050073990928;7923.801904952422;7978.281954624112;8024.919750802417;5438.184272359178;5754.136879468113;5797.927383434078;5952.255968003999;6046.365131661289;5109.514596113363;5782.987977104074;5918.970405147947;6030.819075427744;6114.969425152845 05:07:14 INFO - PROCESS | 2308 | |4;prototype.html;334.206260132186;348.00817669641526;355.6656742661896;382.69384492406084;383.2316206006959;4701.848047228434;4754.595859351951;4863;5052.898942021157;5065.620078494118;3905.2580009798116;4355.867474456641;4370.8688739337795;4875.658703890729;4938.925916111252;3492.6507349265125;3651.2332410193826;3686.520752302197;4198.1393814268;4235.830566777326;3855.768653880762;3867.8839634811;4188.5392606813375;4208.6633069354375;4286.97856510717;3757.380032294668;3916.060145565072;4214.1440581867555;4236.406903033578;4261.722988005769;3604.945925811115;3964.759910650473;4073.959260407388;4203;4204.1801848639525;3819.2934307153264;3837.424386342043;3840.327942610032;3843.8270277837564;3857.9745633542334;3967.2462232175794;4019.678425725935;4973.751312434364;5027.396712394499;5058.342415486;4200.353777120426;4378.0806030733265;4611.071969299485;4803.375561177059;4838.395200599925;4488.708233964814;4728.527147285278;5036.924446133311;5279.683219006872;5386.007177344369;3760.1915588148404;3887.086534664352;3910.393888947218;4214.199302132615;4214.7471151731;4646.93120582268;4851.611871050308;4873.853784386439;4976.626752993504;5263.315768950012;4161;4195.433616461739;4212.431321771522;4377.584129507692;4386.4736231652405;4369.257226271541;4389.934150987738;4446.488653804854;4455.2847153846005;4455.598996090367;4343.978280108642;4727.4327080749845;4731.905361892743;4795.0750042474065;4820.71075735457;4802.807887684454;5149.487011141753;5207.843764687065;5207.869803254949;5217;1301.73965206959;1318.7494376068516;1471.8822494200438;1472.4551915791226;1508.4644951042221;1058.899404556566;1067.6209945469402;1137.6587023892798;1143.89704926557;1155.9768804623861;4538.478075021415;4553.15427857589;4601.44782626087;4650.023495066006;4927.556519913225;4717.646176536773;4994.90010199794;5220.660657057279;5511.669299842022;5514.531264842533;3734.412874528369;4425.468943726773;4992.425871024806;5013.490556610387;5053.595712343077;2376.857388556692;2610.5953577195564;2821.7161191657747;2858;2872.6696429910826;5508.006695146068;5627.521660658808;5716.685582293014;5722.37053924068;5733.455321744427;4797.136515427256;5142.189326061526;5191.844244672666;5284.339457567804;5689.886202275931;5504.981578408007;5553.527950124285;5647.0964645656495;5720.368630429883;5776.42235776419;5523.8895222096135;5555.444455554412;5576.721163941867;5592.608517403743;5623.522000629992;5157.613179011514;5195.896082078413;5407.324084489439;5425.728713564385;5481.972590137024;4970.552650261494;5102.438731739431;5525.806596769094;5534.308211473566;5770.740316685843;3790.450384694259;4050.837966481308;4117.094239267476;4378.452693413324;4403.625691816232;4856.927146092741;5230.25403241798;5444.972775136099;5498.725063746717;5530.308711411074;4789.329493870791;5132.717700526507;5351.357837059422;5362.865928351823;5408;4708.270218116198;4880.755407371098;5112.824050468339;5225.439534628081;5270.942853145243;4734.437635580181;4794.880127996828;4842.176829938849;5089.931114466003;5151.5106064923775;4792.832250871203;5363.865903352292;5369.543588794996;5373.925214956946;5386.622936394571;4653.46485154198;5223.4776522347465;5264.815731449381;5304.734763261744;5311.380029091164 05:07:14 INFO - PROCESS | 2308 | |5;yui.html;725.4994054102665;728.76679462572;730.7661548304543;744.7467860927284;796.5419883566949;14725.042872213298;15597.376104955802;17326.220320085595;18211.544711382223;18509.53726156847;16123;16151.757723634155;16232.431864884731;16283.755743663854;16475.752863707054;923.4966943016072;948.5399581203111;951.6431338248157;952.7046615549167;953.0088707743972;933.3093510801991;948.506776476232;953.3993584042043;954.5084281594984;955.2644463762898;1007.5214273220198;1009.616345788603;1011.0849681038671;1011.812814629296;1013.4831236069584;1002.709214327844;1004.36724863336;1005.1355833982806;1006.66276797273;1008.8385858262643;740.126650552348;742.7474658616096;743.8066102813308;744.0401881572765;745.3366503811612;15305.08169509833;15652.843471565366;15692.607684807972;15876.603084922854;16137.757933631005;13745.762881340628;14012.509562165274;14030.789538156936;14143.858561414358;14156.929215353857;10059.949700251525;11549.307041577533;11635.243709158878;11831.763364732744;11961;3471.878484253039;3587.4080776671817;3590.5870824855215;3611.1513794258326;3754.4743735877;109470;109483.45258273737;110666.44666776614;111450.88549114486;112394.43802780933;10531.947340263403;10554.788904222027;10815;10876.564937402416;10959;22201.333959981228;22485;22499.32502024942;22509.212177574034;22545.32364029082;7384.889226661604;7400.111986561647;7472.140703819022;7532.472726909174;7702.614869256515;3661.6338366163704;4148.91702165961;4316.633086187647;4331.371951067141;4438.201123797748;3347.1130150510135;3372.359251742161;3432.5537680101925;3435.656434356594;3443.9827800860835;617.1236843681983;631.835722712089;633.7496688807987;649.162580271454;649.6751624187906;550.3808215757273;580.9049940861506;601.9398060194034;624.2290770897937;640.3884290502559;10032.799344013225;10060.849087263698;10104.444255565868;10251.58993640246;10487.318324308895;524.3576618642209;574.5059249045818;583.5681595619211;587.2805812879188;590.2503820148388;4761.023990082025;4948.268106162815;4974.626902982219;4989.301497790312;5006.524380183877;80948;94214.0578594219;104241;106018;106644;53201.73399133057;60201.69899150476;60801;62501.68749156316;88729.55635221783;67107;74967.87548186781;75727.62136189285;76645;77217;10349.016843399864;10860.131189504662;10947.726306842393;11117.444127793417;11126.276792008493;40536.3919541213;49442;49861.501384985684;50958.74520627373;50980.490195097576;36742.8162859184;64040.67979660072;65892.67053664701;66323.33676663364;67404;11078.612248571262;11184.30176119808;11972.102092342935;12354;12509.562165324534;14120.364583593968;14174.858251417767;14228.502002429865;14402.351894164578;14493.420263189355;13100.737985240432;13440.193588384729;13660.726785464036;13672.042956992915;14094.41027145598;12923.418446170133;13070.80393794113;13755.518556850462;13760.449582016607;13919.860801391857;12705.491780328683;13314.533991310258;13492.257925813781;13756.793648095487;13942.163470191821;8936.46381217129;9352.438853668802;9455.716328510156;9460.243180545678;9863.852042219516;7624;9550.617975280911;10015.298929074896;11196.776064478501;11343.659690209306 05:07:14 INFO - PROCESS | 2308 | __end_tp_report 05:07:14 INFO - PROCESS | 2308 | __start_cc_report 05:07:14 INFO - PROCESS | 2308 | _x_x_mozilla_cycle_collect,2667 05:07:14 INFO - PROCESS | 2308 | __end_cc_report 05:07:14 INFO - PROCESS | 2308 | __startTimestamp1492517234284__endTimestamp 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | ------- Summary: start ------- 05:07:14 INFO - PROCESS | 2308 | Number of tests: 6 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | [#0] dojo.html Cycles:165 Average:5861.91 Median:6097.09 stddev:632.95 (10.4%) stddev-sans-first:603.68 05:07:14 INFO - PROCESS | 2308 | Values: 3359.3 3375.8 3378.2 3386.3 3414.5 5833.4 5856.6 5922.1 5944.4 5960.9 5859.5 5941.0 5943.5 5943.9 5959.7 5275.3 5318.7 5336.4 5388.5 5453.1 5243.4 5270.4 5324.3 5463.9 5469.9 5351.9 5376.9 5397.7 5401.0 5462.3 5406.2 5408.6 5408.8 5410.7 5439.4 5160.3 5274.0 5330.7 5346.7 5414.9 5732.1 5746.2 5764.2 5776.1 5788.3 6228.4 6265.6 6320.3 6334.6 6355.9 6249.3 6306.5 6328.8 6335.8 6347.2 5982.2 6054.2 6087.9 6091.8 6097.1 6215.9 6263.0 6290.4 6294.6 6433.3 6194.6 6229.1 6236.1 6315.3 6358.6 5394.6 6262.5 6323.1 6330.8 6352.8 5376.0 5401.5 6267.0 6293.1 6307.4 6208.8 6240.1 6243.2 6248.0 6271.6 5126.2 5137.6 5137.7 5144.5 5179.9 4541.3 4597.6 4599.9 4611.3 4627.0 5673.2 5734.1 5783.6 5884.2 5911.7 6254.8 6261.2 6330.2 6337.4 6371.8 5865.1 5898.9 5900.6 5900.8 5950.8 5360.9 5616.4 5616.5 5617.9 5618.0 6311.2 6316.5 6373.1 6418.0 6449.1 6310.9 6323.0 6325.5 6325.6 6326.8 6490.2 6512.2 6515.2 6515.7 6534.3 6149.3 6175.4 6255.6 6262.6 6266.2 6240.4 6288.0 6338.2 6345.4 6358.2 6222.0 6287.9 6304.5 6318.9 6349.2 6315.1 6332.2 6340.4 6355.7 6360.6 5958.6 5987.4 6001.9 6008.7 6011.3 6179.9 6193.8 6233.2 6326.6 6334.3 6121.0 6130.6 6143.8 6158.2 6165.0 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | [#1] ext.html Cycles:180 Average:53001.99 Median:32063.86 stddev:103133.59 (321.7%) stddev-sans-first:103348.20 05:07:14 INFO - PROCESS | 2308 | Values: 717.9 788.5 858.6 864.7 873.6 27169.0 38411.8 39339.4 41264.6 41402.6 40437.2 40567.8 40640.0 40770.0 40837.8 7330.7 8880.7 9059.0 9079.1 9095.4 7337.1 10959.0 11216.5 11268.0 11286.4 11087.9 11132.9 11135.4 11148.9 11216.7 11073.2 11103.6 11125.0 11126.4 11151.0 7249.5 7447.4 7458.2 7459.7 7459.9 38238.6 38806.6 38823.4 38824.2 38855.0 3186.9 3192.2 3197.4 3238.2 3321.4 26582.1 26933.3 26980.6 28786.6 28882.6 3005.0 3136.5 3141.1 3260.6 3267.9 306239.0 308190.0 308395.9 308618.0 309411.0 42133.7 42973.6 48329.9 51233.0 51240.5 52478.7 52905.7 54972.9 55065.7 55241.0 14021.6 14206.3 14212.0 14217.4 14267.9 11750.4 12007.3 12029.6 12086.6 12123.8 13235.0 13260.6 13288.4 13327.7 13402.5 5749.9 5975.6 6011.6 6019.2 6027.7 930.8 950.7 952.8 959.9 986.8 20510.8 26392.9 27648.2 27711.3 28144.9 1019.9 1046.6 1047.2 1051.7 1065.7 11519.9 11711.0 11815.4 13162.9 13880.4 448969.0 452803.0 453637.0 455059.0 455400.0 56416.0 56613.0 56853.7 57002.4 57083.7 1428.2 1457.0 1457.3 1458.9 1460.2 26416.3 26536.0 27452.2 27744.2 27810.0 45867.6 45909.0 46095.0 46108.0 46142.8 393209.0 393352.0 393353.0 394081.0 394118.0 27499.5 32825.9 35239.6 37688.4 38455.0 45109.8 45294.5 45442.5 45814.9 46519.5 30248.0 30805.0 30807.1 31344.2 31888.0 24200.5 31050.8 31614.8 31633.8 31684.0 36651.8 37651.8 37780.8 37822.0 37874.8 29782.4 30120.8 31013.2 32035.7 32092.0 22885.2 23820.9 24457.5 24952.3 25307.0 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | [#2] jquery.html Cycles:180 Average:42701.43 Median:2878.32 stddev:131355.87 (4563.6%) stddev-sans-first:131685.92 05:07:14 INFO - PROCESS | 2308 | Values: 382.7 411.7 425.5 433.7 448.2 14150.8 20292.9 20450.8 20658.2 20893.2 21275.9 22194.3 22345.9 22407.3 22707.9 5227.7 6384.7 6636.8 6652.7 6658.7 6524.8 6586.9 6622.5 6638.4 6663.3 6362.4 6597.3 6649.0 6670.9 6692.1 5513.4 6617.6 6658.0 6666.3 6680.1 7183.1 7243.8 7258.9 7268.6 7270.2 24927.9 25821.0 25943.9 26078.1 26418.6 2801.0 2874.4 2882.2 2892.3 2957.3 13728.6 15622.8 15812.0 15862.3 16659.5 1942.7 2031.3 2033.3 2047.2 2054.1 124778.4 126606.0 129690.7 130392.0 132784.0 13743.9 14144.1 16260.5 16423.4 17842.7 27781.0 28279.0 28398.1 28845.0 28910.6 8126.8 8214.7 8735.5 8843.0 9864.7 7159.7 8131.1 8156.9 8160.2 8290.7 8933.4 8948.6 9099.4 9101.2 9123.3 3380.7 3602.6 3725.9 3884.6 3948.2 842.9 904.4 928.4 952.7 1031.6 19780.3 20688.0 21277.5 23227.4 23555.4 915.7 920.9 926.5 945.5 946.4 6928.9 6957.8 7032.5 7137.5 7152.2 777606.0 779256.0 795180.0 797972.0 803573.0 158532.2 159946.4 165995.3 166331.3 166735.2 1225.1 1247.7 1276.3 1284.2 1285.2 12440.6 12598.7 12719.6 12771.8 12773.6 51391.7 54578.0 57418.7 57512.0 58262.4 60912.0 77413.6 82595.8 91284.0 91696.2 10967.9 11052.9 11268.7 11477.9 11705.9 10890.0 12829.0 12919.5 12965.5 13010.3 11179.8 11259.0 11266.8 11345.3 11381.1 9114.4 9672.0 9729.4 10460.5 10576.6 10124.1 11385.1 11501.4 11681.3 11751.5 9302.4 9651.5 10969.3 11268.0 11425.3 10834.0 11107.6 11330.0 11355.4 11523.1 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | [#3] mootools.html Cycles:180 Average:6615.66 Median:3248.39 stddev:4128.77 (127.1%) stddev-sans-first:4122.72 05:07:14 INFO - PROCESS | 2308 | Values: 1537.5 1560.0 1582.1 1596.6 1597.2 10621.6 11167.8 11177.9 11178.3 11201.6 10725.8 10873.6 11096.7 11179.9 11214.9 1974.0 2389.4 2390.7 2391.8 2397.5 2303.4 2361.6 2392.3 2392.5 2395.7 2303.9 2351.3 2352.1 2391.3 2409.8 2331.5 2335.2 2357.2 2359.1 2365.7 2354.7 2355.0 2355.4 2360.1 2360.7 10945.1 10965.8 11007.5 11011.4 11016.3 5299.3 5631.5 5636.5 5641.2 5646.4 3226.1 3270.7 3453.6 3501.2 3543.3 1075.7 1127.2 1136.2 1177.7 1182.2 12834.5 13650.0 13974.1 14280.4 15235.5 4078.5 4199.0 4225.0 4438.1 4586.3 11496.5 11544.2 11559.7 11574.0 11582.2 5535.9 5536.4 5544.1 5552.6 5553.2 5011.0 5185.2 5219.3 5224.0 5257.2 3612.3 3744.2 3762.9 3804.3 3817.8 870.3 954.1 958.6 968.3 971.5 909.5 917.9 930.1 942.7 979.5 5984.3 6379.7 6385.6 6570.5 6633.8 803.1 973.5 976.4 999.1 1006.5 3871.4 4076.0 4108.1 4136.5 4153.7 13208.2 13846.4 13879.6 13918.6 13970.7 11751.4 12731.0 13134.5 13708.5 13784.7 9867.4 10140.2 10162.0 10163.1 10167.6 6603.3 6649.9 6661.1 6818.4 6831.4 10392.5 11763.6 12145.8 12262.9 12345.5 11348.1 11399.7 11726.8 12552.0 12558.0 8806.2 9153.6 9437.5 9494.1 9536.8 10326.4 10335.5 10349.0 10443.7 10479.3 8823.6 8854.7 8869.9 8919.3 8919.9 8516.7 8619.0 8699.9 8721.1 8730.5 7882.4 7916.1 7923.8 7978.3 8024.9 5438.2 5754.1 5797.9 5952.3 6046.4 5109.5 5783.0 5919.0 6030.8 6115.0 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | [#4] prototype.html Cycles:180 Average:4409.64 Median:4771.96 stddev:1237.30 (25.9%) stddev-sans-first:1202.36 05:07:14 INFO - PROCESS | 2308 | Values: 334.2 348.0 355.7 382.7 383.2 4701.8 4754.6 4863.0 5052.9 5065.6 3905.3 4355.9 4370.9 4875.7 4938.9 3492.7 3651.2 3686.5 4198.1 4235.8 3855.8 3867.9 4188.5 4208.7 4287.0 3757.4 3916.1 4214.1 4236.4 4261.7 3604.9 3964.8 4074.0 4203.0 4204.2 3819.3 3837.4 3840.3 3843.8 3858.0 3967.2 4019.7 4973.8 5027.4 5058.3 4200.4 4378.1 4611.1 4803.4 4838.4 4488.7 4728.5 5036.9 5279.7 5386.0 3760.2 3887.1 3910.4 4214.2 4214.7 4646.9 4851.6 4873.9 4976.6 5263.3 4161.0 4195.4 4212.4 4377.6 4386.5 4369.3 4389.9 4446.5 4455.3 4455.6 4344.0 4727.4 4731.9 4795.1 4820.7 4802.8 5149.5 5207.8 5207.9 5217.0 1301.7 1318.7 1471.9 1472.5 1508.5 1058.9 1067.6 1137.7 1143.9 1156.0 4538.5 4553.2 4601.4 4650.0 4927.6 4717.6 4994.9 5220.7 5511.7 5514.5 3734.4 4425.5 4992.4 5013.5 5053.6 2376.9 2610.6 2821.7 2858.0 2872.7 5508.0 5627.5 5716.7 5722.4 5733.5 4797.1 5142.2 5191.8 5284.3 5689.9 5505.0 5553.5 5647.1 5720.4 5776.4 5523.9 5555.4 5576.7 5592.6 5623.5 5157.6 5195.9 5407.3 5425.7 5482.0 4970.6 5102.4 5525.8 5534.3 5770.7 3790.5 4050.8 4117.1 4378.5 4403.6 4856.9 5230.3 5445.0 5498.7 5530.3 4789.3 5132.7 5351.4 5362.9 5408.0 4708.3 4880.8 5112.8 5225.4 5270.9 4734.4 4794.9 4842.2 5089.9 5151.5 4792.8 5363.9 5369.5 5373.9 5386.6 4653.5 5223.5 5264.8 5304.7 5311.4 05:07:14 INFO - PROCESS | 2308 | 05:07:14 INFO - PROCESS | 2308 | [#5] yui.html Cycles:180 Average:19509.09 Median:12946.22 stddev:27734.72 (214.2%) stddev-sans-first:27776.67 05:07:14 INFO - PROCESS | 2308 | Values: 725.5 728.8 730.8 744.7 796.5 14725.0 15597.4 17326.2 18211.5 18509.5 16123.0 16151.8 16232.4 16283.8 16475.8 923.5 948.5 951.6 952.7 953.0 933.3 948.5 953.4 954.5 955.3 1007.5 1009.6 1011.1 1011.8 1013.5 1002.7 1004.4 1005.1 1006.7 1008.8 740.1 742.7 743.8 744.0 745.3 15305.1 15652.8 15692.6 15876.6 16137.8 13745.8 14012.5 14030.8 14143.9 14156.9 10059.9 11549.3 11635.2 11831.8 11961.0 3471.9 3587.4 3590.6 3611.2 3754.5 109470.0 109483.5 110666.4 111450.9 112394.4 10531.9 10554.8 10815.0 10876.6 10959.0 22201.3 22485.0 22499.3 22509.2 22545.3 7384.9 7400.1 7472.1 7532.5 7702.6 3661.6 4148.9 4316.6 4331.4 4438.2 3347.1 3372.4 3432.6 3435.7 3444.0 617.1 631.8 633.7 649.2 649.7 550.4 580.9 601.9 624.2 640.4 10032.8 10060.8 10104.4 10251.6 10487.3 524.4 574.5 583.6 587.3 590.3 4761.0 4948.3 4974.6 4989.3 5006.5 80948.0 94214.1 104241.0 106018.0 106644.0 53201.7 60201.7 60801.0 62501.7 88729.6 67107.0 74967.9 75727.6 76645.0 77217.0 10349.0 10860.1 10947.7 11117.4 11126.3 40536.4 49442.0 49861.5 50958.7 50980.5 36742.8 64040.7 65892.7 66323.3 67404.0 11078.6 11184.3 11972.1 12354.0 12509.6 14120.4 14174.9 14228.5 14402.4 14493.4 13100.7 13440.2 13660.7 13672.0 14094.4 12923.4 13070.8 13755.5 13760.4 13919.9 12705.5 13314.5 13492.3 13756.8 13942.2 8936.5 9352.4 9455.7 9460.2 9863.9 7624.0 9550.6 10015.3 11196.8 11343.7 05:07:14 INFO - PROCESS | 2308 | -------- Summary: end -------- 05:07:14 INFO - PROCESS | 2308 | 05:07:15 INFO - TEST-INFO | 2308: exit 0 05:07:15 INFO - TEST-OK | dromaeo_css | took 1120358ms 05:07:15 INFO - TEST-START | kraken 05:07:15 INFO - operating with platform_type : mac_ 05:07:15 INFO - Initialising browser for kraken test... 05:07:15 INFO - TEST-INFO | started process 2310 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpRMIxTB/profile http://localhost:56107/getInfo.html) 05:07:17 INFO - PROCESS | 2310 | __metrics Screen width/height:1600/1200 05:07:17 INFO - PROCESS | 2310 | colorDepth:24 05:07:17 INFO - PROCESS | 2310 | Browser inner width/height: 1024/689 05:07:17 INFO - PROCESS | 2310 | __metrics 05:07:17 INFO - PROCESS | 2310 | 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" 05:07:17 INFO - PROCESS | 2310 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:07:18 INFO - TEST-INFO | 2310: exit 0 05:07:18 INFO - Browser initialized. 05:07:18 INFO - Running cycle 1/1 for kraken test... 05:07:18 INFO - TEST-INFO | started process 2311 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpRMIxTB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:07:30 INFO - PROCESS | 2311 | RSS: Main: 210714624 05:07:30 INFO - PROCESS | 2311 | 05:07:33 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56107/tests/kraken/driver.html?testName=audio-beat-detection) 05:07:34 INFO - PROCESS | 2311 | RSS: Main: 264339456 05:07:34 INFO - PROCESS | 2311 | 05:07:36 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56107/tests/kraken/driver.html?testName=audio-dft) 05:07:37 INFO - PROCESS | 2311 | RSS: Main: 279277568 05:07:37 INFO - PROCESS | 2311 | 05:07:40 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56107/tests/kraken/driver.html?testName=audio-fft) 05:07:41 INFO - PROCESS | 2311 | RSS: Main: 376762368 05:07:41 INFO - PROCESS | 2311 | 05:07:43 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56107/tests/kraken/driver.html?testName=audio-oscillator) 05:07:44 INFO - PROCESS | 2311 | RSS: Main: 277929984 05:07:44 INFO - PROCESS | 2311 | 05:07:45 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56107/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:07:46 INFO - PROCESS | 2311 | RSS: Main: 279298048 05:07:46 INFO - PROCESS | 2311 | 05:07:49 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56107/tests/kraken/driver.html?testName=imaging-darkroom) 05:07:50 INFO - PROCESS | 2311 | RSS: Main: 319369216 05:07:50 INFO - PROCESS | 2311 | 05:07:53 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56107/tests/kraken/driver.html?testName=imaging-desaturate) 05:07:53 INFO - PROCESS | 2311 | RSS: Main: 320278528 05:07:53 INFO - PROCESS | 2311 | 05:07:56 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56107/tests/kraken/driver.html?testName=json-parse-financial) 05:07:57 INFO - PROCESS | 2311 | RSS: Main: 317906944 05:07:57 INFO - PROCESS | 2311 | 05:07:59 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56107/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:07:59 INFO - PROCESS | 2311 | RSS: Main: 296243200 05:07:59 INFO - PROCESS | 2311 | 05:08:01 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:08:02 INFO - PROCESS | 2311 | RSS: Main: 299319296 05:08:02 INFO - PROCESS | 2311 | 05:08:04 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:05 INFO - PROCESS | 2311 | RSS: Main: 306839552 05:08:05 INFO - PROCESS | 2311 | 05:08:08 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:09 INFO - PROCESS | 2311 | RSS: Main: 321228800 05:08:09 INFO - PROCESS | 2311 | 05:08:12 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:12 INFO - PROCESS | 2311 | RSS: Main: 314028032 05:08:12 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:56107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56107/tests/kraken/driver.html?testName=ai-astar) 05:08:14 INFO - PROCESS | 2311 | RSS: Main: 358207488 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | __start_tp_report 05:08:14 INFO - PROCESS | 2311 | _x_x_mozilla_page_load 05:08:14 INFO - PROCESS | 2311 | _x_x_mozilla_page_load_details 05:08:14 INFO - PROCESS | 2311 | |i|pagename|runs| 05:08:14 INFO - PROCESS | 2311 | |0;ai-astar;106;115;114;116;116;117;116;122;166;151 05:08:14 INFO - PROCESS | 2311 | |1;audio-beat-detection;166;138;137;147;112;111;109;110;131;112 05:08:14 INFO - PROCESS | 2311 | |2;audio-dft;183;186;184;187;186;184;186;187;184;185 05:08:14 INFO - PROCESS | 2311 | |3;audio-fft;82;60;60;92;79;82;64;60;78;61 05:08:14 INFO - PROCESS | 2311 | |4;audio-oscillator;85;70;85;68;69;69;82;68;68;81 05:08:14 INFO - PROCESS | 2311 | |5;imaging-gaussian-blur;89;88;88;88;89;89;88;88;88;88 05:08:14 INFO - PROCESS | 2311 | |6;imaging-darkroom;96;95;94;95;95;95;95;94;95;97 05:08:14 INFO - PROCESS | 2311 | |7;imaging-desaturate;86;84;84;85;85;84;110;128;127;84 05:08:14 INFO - PROCESS | 2311 | |8;json-parse-financial;48;48;82;92;94;96;86;65;63;64 05:08:14 INFO - PROCESS | 2311 | |9;json-stringify-tinderbox;53;66;63;63;61;58;57;57;57;57 05:08:14 INFO - PROCESS | 2311 | |10;stanford-crypto-aes;96;97;84;72;83;107;99;86;89;86 05:08:14 INFO - PROCESS | 2311 | |11;stanford-crypto-ccm;164;140;140;148;136;137;136;139;178;162 05:08:14 INFO - PROCESS | 2311 | |12;stanford-crypto-pbkdf2;165;169;161;158;166;170;212;201;176;214 05:08:14 INFO - PROCESS | 2311 | |13;stanford-crypto-sha256-iterative;62;61;63;66;65;65;62;64;64;63 05:08:14 INFO - PROCESS | 2311 | __end_tp_report 05:08:14 INFO - PROCESS | 2311 | __start_cc_report 05:08:14 INFO - PROCESS | 2311 | _x_x_mozilla_cycle_collect,2286 05:08:14 INFO - PROCESS | 2311 | __end_cc_report 05:08:14 INFO - PROCESS | 2311 | __startTimestamp1492517294671__endTimestamp 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | ------- Summary: start ------- 05:08:14 INFO - PROCESS | 2311 | Number of tests: 14 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#0] ai-astar Cycles:10 Average:123.90 Median:116.50 stddev:18.98 (16.3%) stddev-sans-first:19.00 05:08:14 INFO - PROCESS | 2311 | Values: 106.0 115.0 114.0 116.0 116.0 117.0 116.0 122.0 166.0 151.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:134.00 stddev:19.65 (14.7%) stddev-sans-first:15.05 05:08:14 INFO - PROCESS | 2311 | Values: 166.0 138.0 137.0 147.0 112.0 111.0 109.0 110.0 131.0 112.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#2] audio-dft Cycles:10 Average:185.20 Median:186.00 stddev:1.40 (0.8%) stddev-sans-first:1.24 05:08:14 INFO - PROCESS | 2311 | Values: 183.0 186.0 184.0 187.0 186.0 184.0 186.0 187.0 184.0 185.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#3] audio-fft Cycles:10 Average:71.80 Median:78.50 stddev:12.03 (15.3%) stddev-sans-first:12.18 05:08:14 INFO - PROCESS | 2311 | Values: 82.0 60.0 60.0 92.0 79.0 82.0 64.0 60.0 78.0 61.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#4] audio-oscillator Cycles:10 Average:74.50 Median:75.50 stddev:7.65 (10.1%) stddev-sans-first:7.11 05:08:14 INFO - PROCESS | 2311 | Values: 85.0 70.0 85.0 68.0 69.0 69.0 82.0 68.0 68.0 81.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 05:08:14 INFO - PROCESS | 2311 | Values: 89.0 88.0 88.0 88.0 89.0 89.0 88.0 88.0 88.0 88.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.88 (0.9%) stddev-sans-first:0.87 05:08:14 INFO - PROCESS | 2311 | Values: 96.0 95.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 97.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:84.00 stddev:18.55 (22.1%) stddev-sans-first:19.34 05:08:14 INFO - PROCESS | 2311 | Values: 86.0 84.0 84.0 85.0 85.0 84.0 110.0 128.0 127.0 84.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#8] json-parse-financial Cycles:10 Average:73.80 Median:84.00 stddev:18.47 (22.0%) stddev-sans-first:17.07 05:08:14 INFO - PROCESS | 2311 | Values: 48.0 48.0 82.0 92.0 94.0 96.0 86.0 65.0 63.0 64.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.50 stddev:3.91 (6.6%) stddev-sans-first:3.44 05:08:14 INFO - PROCESS | 2311 | Values: 53.0 66.0 63.0 63.0 61.0 58.0 57.0 57.0 57.0 57.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:87.50 stddev:9.98 (11.4%) stddev-sans-first:10.34 05:08:14 INFO - PROCESS | 2311 | Values: 96.0 97.0 84.0 72.0 83.0 107.0 99.0 86.0 89.0 86.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:144.00 stddev:14.79 (10.3%) stddev-sans-first:14.52 05:08:14 INFO - PROCESS | 2311 | Values: 164.0 140.0 140.0 148.0 136.0 137.0 136.0 139.0 178.0 162.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:173.00 stddev:21.39 (12.4%) stddev-sans-first:22.06 05:08:14 INFO - PROCESS | 2311 | Values: 165.0 169.0 161.0 158.0 166.0 170.0 212.0 201.0 176.0 214.0 05:08:14 INFO - PROCESS | 2311 | 05:08:14 INFO - PROCESS | 2311 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:64.00 stddev:1.58 (2.5%) stddev-sans-first:1.58 05:08:14 INFO - PROCESS | 2311 | Values: 62.0 61.0 63.0 66.0 65.0 65.0 62.0 64.0 64.0 63.0 05:08:14 INFO - PROCESS | 2311 | -------- Summary: end -------- 05:08:14 INFO - PROCESS | 2311 | 05:08:15 INFO - TEST-INFO | 2311: exit 0 05:08:15 INFO - TEST-OK | kraken | took 60594ms 05:08:15 INFO - SUITE-END | took 1180s 05:08:15 INFO - Completed test suite (00:19:41) 05:08:15 INFO - javascript benchmark 05:08:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7481.320970924446, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3359.344927739092, 3375.831208439578, 3378.155461134717, 3386.3058073095017, 3414.5048967899656, 5833.416658334165, 5856.648601083938, 5922.082077278029, 5944.375840536746, 5960.910586341203, 5859.472647461738, 5941.0494320908665, 5943.494802941756, 5943.910841337383, 5959.672218028018, 5275.340582427197, 5318.680879147263, 5336.439673834249, 5388.488093631099, 5453.100238460649, 5243.4232234454175, 5270.420253772101, 5324.281222035033, 5463.9180412293845, 5469.863253418656, 5351.892962140755, 5376.892462150755, 5397.730113494329, 5401.0, 5462.262594549725, 5406.189071639246, 5408.567314614841, 5408.756605952742, 5410.675359478405, 5439.374471935718, 5160.303359046518, 5274.024305503494, 5330.706811125387, 5346.679199248058, 5414.91877621836, 5732.140178973145, 5746.166805813175, 5764.221830052931, 5776.133579963039, 5788.305403351583, 6228.37716228378, 6265.624062556261, 6320.304766475684, 6334.619922804646, 6355.872882542323, 6249.250089989185, 6306.49548036156, 6328.778492752777, 6335.778247761306, 6347.238331400215, 5982.162497250389, 6054.212952316215, 6087.908681369828, 6091.817245482643, 6097.085437184369, 6215.906761398583, 6263.0, 6290.402411770874, 6294.590851594632, 6433.324500927429, 6194.5973511721595, 6229.065640153923, 6236.126942228092, 6315.336889626615, 6358.586691865014, 5394.595405344615, 6262.467690246289, 6323.083152942798, 6330.8100756977365, 6352.841178970562, 5376.0, 5401.4868587484125, 6267.0, 6293.087502312139, 6307.432331090224, 6208.782692605737, 6240.095186197976, 6243.157173781572, 6247.969085100907, 6271.62370257786, 5126.231065340229, 5137.614678899097, 5137.691738495702, 5144.511271429208, 5179.896402072013, 4541.296099104644, 4597.632189424838, 4599.9080018399445, 4611.262198048296, 4627.005193883264, 5673.205751194836, 5734.139879018181, 5783.624064435799, 5884.176215329857, 5911.674857882772, 6254.781082662176, 6261.24865016201, 6330.208723909512, 6337.366263373718, 6371.840703982438, 5865.061590145556, 5898.911516327258, 5900.586958912921, 5900.76396944126, 5950.761969521257, 5360.8659783504945, 5616.438356164351, 5616.494515493625, 5617.915731264035, 5617.9719101404235, 6311.1795466589965, 6316.463100636497, 6373.139626150596, 6418.0, 6449.097126402401, 6310.936890631218, 6322.968385158045, 6325.4939604832425, 6325.588836725598, 6326.778562750282, 6490.156279683611, 6512.218533775977, 6515.153030106056, 6515.73937042513, 6534.346565343428, 6149.323574406902, 6175.382461753788, 6255.624662520263, 6262.5929314594405, 6266.216722909637, 6240.438360547572, 6288.025356069634, 6338.207724034496, 6345.428911397997, 6358.237011558643, 6222.0355844844125, 6287.874242515032, 6304.464120549805, 6318.936810632019, 6349.206349206349, 6315.052742088724, 6332.240131184288, 6340.397662222082, 6355.682215889095, 6360.618362898241, 5958.582899197015, 5987.401259873977, 6001.939980600313, 6008.699565021644, 6011.338752737282, 6179.876402472015, 6193.845153871009, 6233.2208473940755, 6326.620402775849, 6334.271558770616, 6120.969395152996, 6130.550643787619, 6143.754249830135, 6158.168647232566, 6165.0], "value": 5821.862001141777, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.9246179151189, 788.4914230321444, 858.6436628799049, 864.7492227254097, 873.5719497446249, 27169.0, 38411.807940960294, 39339.40990885132, 41264.58735412645, 41402.58597414025, 40437.191256174934, 40567.797161014154, 40639.98400040001, 40770.0, 40837.7958110209, 7330.706771729151, 8880.733577992654, 9059.0, 9079.0920907909, 9095.408303546887, 7337.119545654529, 10959.01368876801, 11216.49525771338, 11268.042216411615, 11286.379249141293, 11087.944560277187, 11132.944335278353, 11135.387553684544, 11148.888511114867, 11216.66350009497, 11073.169512286571, 11103.55585776568, 11125.0, 11126.38804865732, 11150.99641032311, 7249.456290778213, 7447.404207663374, 7458.216887226871, 7459.738909138154, 7459.850802983964, 38238.617613823786, 38806.61193388058, 38823.41764873529, 38824.22351552981, 38855.0, 3186.9043928682177, 3192.201949512622, 3197.392495425862, 3238.239013831748, 3321.4021476134285, 26582.06962756294, 26933.32666683329, 26980.595291070455, 28786.568201476995, 28882.566761498594, 3005.0, 3136.498160294342, 3141.1361875484195, 3260.6413294537583, 3267.918302042468, 306239.0, 308190.0, 308395.9160408367, 308618.0, 309411.0, 42133.73598792041, 42973.57026429758, 48329.85855919425, 51233.0, 51240.487595124316, 52478.68803279949, 52905.7354713224, 54972.900541988936, 55065.72467137639, 55241.0, 14021.649458763408, 14206.289685515889, 14212.0, 14217.365003024577, 14267.92866035684, 11750.412479375997, 12007.339596322287, 12029.639110826689, 12086.576969806014, 12123.757524849454, 13235.007374446763, 13260.602181934557, 13288.402021909038, 13327.73344533104, 13402.463901444027, 5749.88500229993, 5975.641461512323, 6011.579189456784, 6019.157317975487, 6027.6986150693165, 930.8138372325562, 950.7147855643279, 952.7570469530225, 959.8560215967522, 986.8273052215977, 20510.79489205089, 26392.8680356597, 27648.170554882985, 27711.307217319325, 28144.8592757039, 1019.8572199891872, 1046.6179844356755, 1047.225053460449, 1051.7107795356337, 1065.6962765611763, 11519.942400287944, 11711.0, 11815.350155741346, 13162.868371316164, 13880.4447822088, 448969.0, 452803.0, 453637.0, 455059.0, 455400.0, 56416.0, 56613.0, 56853.71573142108, 57002.42997569971, 57083.71458142683, 1428.2073449235775, 1456.992715036418, 1457.2640816387868, 1458.9124652520882, 1460.1531111955253, 26416.339591509597, 26536.0, 27452.209027073706, 27744.167674969784, 27810.0, 45867.62397128092, 45909.0, 46095.0, 46108.0, 46142.76928615336, 393209.0, 393352.0, 393353.0, 394081.0, 394118.0, 27499.45001099927, 32825.851095211554, 35239.647603523634, 37688.43467348047, 38455.0, 45109.77445112754, 45294.54705452903, 45442.54557454383, 45814.85462863322, 46519.534804652874, 30248.0, 30805.0, 30807.075787726404, 31344.21639459032, 31888.0, 24200.515989679756, 31050.844745776125, 31614.841925791145, 31633.780426229416, 31684.0, 36651.816740916125, 37651.81174094112, 37780.811095944344, 37822.0, 37874.810625946695, 29782.404351913272, 30120.84939575288, 31013.224669383446, 32035.679643203268, 32092.03723888287, 22885.199018034287, 23820.880895595412, 24457.51084978255, 24952.251432457055, 25307.0], "value": 18330.81260399287, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.6871162004211, 411.7076875418455, 425.472414206384, 433.68557795598184, 448.20667418668955, 14150.787738183923, 20292.89853550734, 20450.795492045076, 20658.17367305306, 20893.164273429084, 21275.893620531875, 22194.334169974885, 22345.888270558626, 22407.32778016658, 22707.86460676968, 5227.745341118123, 6384.712687929043, 6636.800895973129, 6652.733890644368, 6658.700358483879, 6524.836879078037, 6586.93413065868, 6622.503312251576, 6638.435732962704, 6663.267040625527, 6362.427381535661, 6597.307282735292, 6648.9667551662415, 6670.899936500957, 6692.096566963446, 5513.421090785449, 6617.602943823362, 6657.966710166443, 6666.266710661824, 6680.131582894217, 7183.138023437169, 7243.818904527377, 7258.927410725878, 7268.600226987513, 7270.236625154335, 24927.875360623264, 25821.0, 25943.870280648665, 26078.087266945757, 26418.6037209442, 2801.0196431249105, 2874.425114976992, 2882.206138013154, 2892.291388609804, 2957.31981644223, 13728.588142355746, 15622.843771562253, 15812.0, 15862.320615418479, 16659.500214993568, 1942.660034493958, 2031.3296612117965, 2033.278186243877, 2047.1606641277006, 2054.137262349817, 124778.37610811979, 126606.0, 129690.7030929688, 130392.0, 132783.99999999904, 13743.862561374259, 14144.09988150633, 16260.512184634243, 16423.425180118877, 17842.732359014626, 27781.0, 28279.0, 28398.148055558366, 28845.0, 28910.566341504895, 8126.75619731409, 8214.671413143407, 8735.475871447734, 8842.955785221162, 9864.704058878102, 7159.713611455588, 8131.064927533409, 8156.918430815735, 8160.224778646019, 8290.709825156091, 8933.374663773604, 8948.552572371356, 9099.408538445112, 9101.226395756436, 9123.270138388913, 3380.695737383647, 3602.6402706227464, 3725.8509659613856, 3884.5532763731967, 3948.2498325318097, 842.8693552499373, 904.3619830444568, 928.3594319919234, 952.6903756279237, 1031.566741968375, 19780.30768923081, 20687.965601719854, 21277.46806329854, 23227.419314517272, 23555.41111472227, 915.7344370132587, 920.9493477858782, 926.5400362038798, 945.4658118163215, 946.4274114160971, 6928.861422771516, 6957.79126626202, 7032.542884712478, 7137.464690148156, 7152.177499587614, 777606.0, 779256.0, 795180.0, 797972.0, 803573.0, 158532.20733896256, 159946.4005359955, 165995.3400466004, 166331.336686634, 166735.1663241676, 1225.148521777356, 1247.731737676404, 1276.2661469654986, 1284.1845428153003, 1285.1839082182694, 12440.564580239648, 12598.685032873886, 12719.55481558141, 12771.80842287385, 12773.61679149627, 51391.74304128456, 54578.0, 57418.7129064352, 57512.0, 58262.4173758257, 60912.0, 77413.61293193499, 82595.76106358289, 91284.0, 91696.16607667942, 10967.945160274148, 11052.889471105507, 11268.718282043015, 11477.942610286895, 11705.88294117082, 10890.0, 12829.0, 12919.483220671067, 12965.481380744664, 13010.284434356201, 11179.776404472028, 11258.986691197832, 11266.830997535208, 11345.319280843176, 11381.146414018816, 9114.36199466031, 9672.0, 9729.416235025637, 10460.529276182438, 10576.629817956335, 10124.139448146696, 11385.146114041645, 11501.367424791717, 11681.299122052704, 11751.529938802352, 9302.441853488812, 9651.469169195761, 10969.286996345212, 11268.04221641137, 11425.257358271685, 10834.0, 11107.611233606785, 11330.036946859609, 11355.432228388383, 11523.078153747512], "value": 10853.054559978207, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.4849425442476, 1559.9922000389997, 1582.1456413536691, 1596.6168119651288, 1597.1774536113899, 10621.628243011497, 11167.83248251277, 11177.888221117786, 11178.329300241981, 11201.551937922473, 10725.839112413301, 10873.619423320186, 11096.722581935455, 11179.944100279487, 11214.887851121486, 1974.0, 2389.354874183969, 2390.6653068570413, 2391.7966972807244, 2397.5324811661735, 2303.447172678562, 2361.5749165150264, 2392.342105920868, 2392.461696118377, 2395.688560487134, 2303.9424014399606, 2351.2828587280874, 2352.0826877517784, 2391.2945681024053, 2409.8072154227707, 2331.4870728439714, 2335.1593426366494, 2357.186770564161, 2359.115331750585, 2365.7279412867397, 2354.7292061412986, 2354.9882250588807, 2355.4111472131967, 2360.091364824538, 2360.6695062691238, 10945.069669078059, 10965.780684386267, 11007.504662290215, 11011.449427528672, 11016.339019658846, 5299.33758280215, 5631.465010823965, 5636.520895723827, 5641.238432811559, 5646.378898321223, 3226.0966929259844, 3270.689284517967, 3453.5510383650217, 3501.1772233525103, 3543.255916257563, 1075.688050465364, 1127.239113598326, 1136.2443974756777, 1177.7350096228365, 1182.24927171246, 12834.486620535168, 13650.044496885223, 13974.091684040504, 14280.357383917644, 15235.466758663393, 4078.530968938551, 4198.958010419857, 4224.957750422518, 4438.134563760036, 4586.334981427741, 11496.540138394537, 11544.191906566628, 11559.711007224887, 11574.0, 11582.189246752814, 5535.944640553624, 5536.390996990328, 5544.085225937756, 5552.639078459887, 5553.222548843165, 5011.0, 5185.170372740419, 5219.347581552306, 5224.03355379256, 5257.211418287287, 3612.313660404515, 3744.2137151198554, 3762.9247415051545, 3804.2581696568905, 3817.7900215488417, 870.3385427075342, 954.0710020237942, 958.5542722634009, 968.3221744778683, 971.5482300730195, 909.5179554835947, 917.8623206518942, 930.1256818590504, 942.7030485397078, 979.5102448775613, 5984.3417224105315, 6379.71291291893, 6385.582489598017, 6570.540062195696, 6633.800985970429, 803.0764620686141, 973.5424350555228, 976.3653625143713, 999.0758548342753, 1006.5420233793607, 3871.399933010388, 4075.97962010194, 4108.137291168888, 4136.462259906193, 4153.68847336451, 13208.207507549578, 13846.376913038735, 13879.583612491642, 13918.58244252674, 13970.720585588231, 11751.353675547756, 12730.981521478445, 13134.474621015052, 13708.52020179289, 13784.724305513833, 9867.40795552269, 10140.188784897191, 10161.98380161978, 10163.136133428741, 10167.593296268215, 6603.306652801387, 6649.933500664932, 6661.067450556829, 6818.386345228953, 6831.351021652935, 10392.532336044746, 11763.588274410355, 12145.757084858431, 12262.938685306517, 12345.506179753069, 11348.092152627934, 11399.715007124889, 11726.824097638713, 12552.0, 12558.0, 8806.163414475615, 9153.63385464574, 9437.528123593931, 9494.145526902612, 9536.809263814823, 10326.380417174994, 10335.534900929326, 10348.96510348959, 10443.68668939933, 10479.266451348332, 8823.602937867683, 8854.734357969272, 8869.866951995597, 8919.331050171393, 8919.866202006846, 8516.744497664831, 8618.956905215433, 8699.91300086991, 8721.127887211482, 8730.476171429735, 7882.369410447265, 7916.050073990928, 7923.801904952422, 7978.281954624112, 8024.919750802417, 5438.184272359178, 5754.136879468113, 5797.927383434078, 5952.255968003999, 6046.365131661289, 5109.514596113363, 5782.987977104074, 5918.970405147947, 6030.819075427744, 6114.969425152845], "value": 5030.286859257044, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.206260132186, 348.00817669641526, 355.6656742661896, 382.69384492406084, 383.2316206006959, 4701.848047228434, 4754.595859351951, 4863.0, 5052.898942021157, 5065.620078494118, 3905.2580009798116, 4355.867474456641, 4370.8688739337795, 4875.658703890729, 4938.925916111252, 3492.6507349265125, 3651.2332410193826, 3686.520752302197, 4198.1393814268, 4235.830566777326, 3855.768653880762, 3867.8839634811, 4188.5392606813375, 4208.6633069354375, 4286.97856510717, 3757.380032294668, 3916.060145565072, 4214.1440581867555, 4236.406903033578, 4261.722988005769, 3604.945925811115, 3964.759910650473, 4073.959260407388, 4203.0, 4204.1801848639525, 3819.2934307153264, 3837.424386342043, 3840.327942610032, 3843.8270277837564, 3857.9745633542334, 3967.2462232175794, 4019.678425725935, 4973.751312434364, 5027.396712394499, 5058.342415486, 4200.353777120426, 4378.0806030733265, 4611.071969299485, 4803.375561177059, 4838.395200599925, 4488.708233964814, 4728.527147285278, 5036.924446133311, 5279.683219006872, 5386.007177344369, 3760.1915588148404, 3887.086534664352, 3910.393888947218, 4214.199302132615, 4214.7471151731, 4646.93120582268, 4851.611871050308, 4873.853784386439, 4976.626752993504, 5263.315768950012, 4161.0, 4195.433616461739, 4212.431321771522, 4377.584129507692, 4386.4736231652405, 4369.257226271541, 4389.934150987738, 4446.488653804854, 4455.2847153846005, 4455.598996090367, 4343.978280108642, 4727.4327080749845, 4731.905361892743, 4795.0750042474065, 4820.71075735457, 4802.807887684454, 5149.487011141753, 5207.843764687065, 5207.869803254949, 5217.0, 1301.73965206959, 1318.7494376068516, 1471.8822494200438, 1472.4551915791226, 1508.4644951042221, 1058.899404556566, 1067.6209945469402, 1137.6587023892798, 1143.89704926557, 1155.9768804623861, 4538.478075021415, 4553.15427857589, 4601.44782626087, 4650.023495066006, 4927.556519913225, 4717.646176536773, 4994.90010199794, 5220.660657057279, 5511.669299842022, 5514.531264842533, 3734.412874528369, 4425.468943726773, 4992.425871024806, 5013.490556610387, 5053.595712343077, 2376.857388556692, 2610.5953577195564, 2821.7161191657747, 2858.0, 2872.6696429910826, 5508.006695146068, 5627.521660658808, 5716.685582293014, 5722.37053924068, 5733.455321744427, 4797.136515427256, 5142.189326061526, 5191.844244672666, 5284.339457567804, 5689.886202275931, 5504.981578408007, 5553.527950124285, 5647.0964645656495, 5720.368630429883, 5776.42235776419, 5523.8895222096135, 5555.444455554412, 5576.721163941867, 5592.608517403743, 5623.522000629992, 5157.613179011514, 5195.896082078413, 5407.324084489439, 5425.728713564385, 5481.972590137024, 4970.552650261494, 5102.438731739431, 5525.806596769094, 5534.308211473566, 5770.740316685843, 3790.450384694259, 4050.837966481308, 4117.094239267476, 4378.452693413324, 4403.625691816232, 4856.927146092741, 5230.25403241798, 5444.972775136099, 5498.725063746717, 5530.308711411074, 4789.329493870791, 5132.717700526507, 5351.357837059422, 5362.865928351823, 5408.0, 4708.270218116198, 4880.755407371098, 5112.824050468339, 5225.439534628081, 5270.942853145243, 4734.437635580181, 4794.880127996828, 4842.176829938849, 5089.931114466003, 5151.5106064923775, 4792.832250871203, 5363.865903352292, 5369.543588794996, 5373.925214956946, 5386.622936394571, 4653.46485154198, 5223.4776522347465, 5264.815731449381, 5304.734763261744, 5311.380029091164], "value": 4047.1188304715674, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.4994054102665, 728.76679462572, 730.7661548304543, 744.7467860927284, 796.5419883566949, 14725.042872213298, 15597.376104955802, 17326.220320085595, 18211.544711382223, 18509.53726156847, 16123.0, 16151.757723634155, 16232.431864884731, 16283.755743663854, 16475.752863707054, 923.4966943016072, 948.5399581203111, 951.6431338248157, 952.7046615549167, 953.0088707743972, 933.3093510801991, 948.506776476232, 953.3993584042043, 954.5084281594984, 955.2644463762898, 1007.5214273220198, 1009.616345788603, 1011.0849681038671, 1011.812814629296, 1013.4831236069584, 1002.709214327844, 1004.36724863336, 1005.1355833982806, 1006.66276797273, 1008.8385858262643, 740.126650552348, 742.7474658616096, 743.8066102813308, 744.0401881572765, 745.3366503811612, 15305.08169509833, 15652.843471565366, 15692.607684807972, 15876.603084922854, 16137.757933631005, 13745.762881340628, 14012.509562165274, 14030.789538156936, 14143.858561414358, 14156.929215353857, 10059.949700251525, 11549.307041577533, 11635.243709158878, 11831.763364732744, 11961.0, 3471.878484253039, 3587.4080776671817, 3590.5870824855215, 3611.1513794258326, 3754.4743735877, 109470.0, 109483.45258273737, 110666.44666776614, 111450.88549114486, 112394.43802780933, 10531.947340263403, 10554.788904222027, 10815.0, 10876.564937402416, 10959.0, 22201.333959981228, 22485.0, 22499.32502024942, 22509.212177574034, 22545.32364029082, 7384.889226661604, 7400.111986561647, 7472.140703819022, 7532.472726909174, 7702.614869256515, 3661.6338366163704, 4148.91702165961, 4316.633086187647, 4331.371951067141, 4438.201123797748, 3347.1130150510135, 3372.359251742161, 3432.5537680101925, 3435.656434356594, 3443.9827800860835, 617.1236843681983, 631.835722712089, 633.7496688807987, 649.162580271454, 649.6751624187906, 550.3808215757273, 580.9049940861506, 601.9398060194034, 624.2290770897937, 640.3884290502559, 10032.799344013225, 10060.849087263698, 10104.444255565868, 10251.58993640246, 10487.318324308895, 524.3576618642209, 574.5059249045818, 583.5681595619211, 587.2805812879188, 590.2503820148388, 4761.023990082025, 4948.268106162815, 4974.626902982219, 4989.301497790312, 5006.524380183877, 80948.0, 94214.0578594219, 104241.0, 106018.0, 106644.0, 53201.73399133057, 60201.69899150476, 60801.0, 62501.68749156316, 88729.55635221783, 67107.0, 74967.87548186781, 75727.62136189285, 76645.0, 77217.0, 10349.016843399864, 10860.131189504662, 10947.726306842393, 11117.444127793417, 11126.276792008493, 40536.3919541213, 49442.0, 49861.501384985684, 50958.74520627373, 50980.490195097576, 36742.8162859184, 64040.67979660072, 65892.67053664701, 66323.33676663364, 67404.0, 11078.612248571262, 11184.30176119808, 11972.102092342935, 12354.0, 12509.562165324534, 14120.364583593968, 14174.858251417767, 14228.502002429865, 14402.351894164578, 14493.420263189355, 13100.737985240432, 13440.193588384729, 13660.726785464036, 13672.042956992915, 14094.41027145598, 12923.418446170133, 13070.80393794113, 13755.518556850462, 13760.449582016607, 13919.860801391857, 12705.491780328683, 13314.533991310258, 13492.257925813781, 13756.793648095487, 13942.163470191821, 8936.46381217129, 9352.438853668802, 9455.716328510156, 9460.243180545678, 9863.852042219516, 7624.0, 9550.617975280911, 10015.298929074896, 11196.776064478501, 11343.659690209306], "value": 7435.212072471446, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1475.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 114.0, 116.0, 116.0, 117.0, 116.0, 122.0, 166.0, 151.0], "value": 123.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 138.0, 137.0, 147.0, 112.0, 111.0, 109.0, 110.0, 131.0, 112.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 184.0, 187.0, 186.0, 184.0, 186.0, 187.0, 184.0, 185.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 60.0, 92.0, 79.0, 82.0, 64.0, 60.0, 78.0, 61.0], "value": 71.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 85.0, 68.0, 69.0, 69.0, 82.0, 68.0, 68.0, 81.0], "value": 74.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 88.0, 89.0, 89.0, 88.0, 88.0, 88.0, 88.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 97.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 85.0, 85.0, 84.0, 110.0, 128.0, 127.0, 84.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 82.0, 92.0, 94.0, 96.0, 86.0, 65.0, 63.0, 64.0], "value": 73.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 66.0, 63.0, 63.0, 61.0, 58.0, 57.0, 57.0, 57.0, 57.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 84.0, 72.0, 83.0, 107.0, 99.0, 86.0, 89.0, 86.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 140.0, 140.0, 148.0, 136.0, 137.0, 136.0, 139.0, 178.0, 162.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 161.0, 158.0, 166.0, 170.0, 212.0, 201.0, 176.0, 214.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 63.0, 66.0, 65.0, 65.0, 62.0, 64.0, 64.0, 63.0], "value": 63.5, "unit": "score"}]}]} 05:08:15 INFO - Return code: 0 05:08:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:08:15 ERROR - # TBPL SUCCESS # 05:08:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:08:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:08:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:08:16 INFO - 2017-04-18 05:08:16.007 screenresolution[2315:29359] starting screenresolution argv=screenresolution get 05:08:16 INFO - 2017-04-18 05:08:16.010 screenresolution[2315:29359] Display 0: 1600x1200x32@60 05:08:16 INFO - 2017-04-18 05:08:16.017 screenresolution[2316:29362] starting screenresolution argv=screenresolution list 05:08:16 INFO - Available Modes on Display 0 05:08:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:08:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:08:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:08:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:08:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:08:16 INFO - Intel Iris: 05:08:16 INFO - Chipset Model: Intel Iris 05:08:16 INFO - Type: GPU 05:08:16 INFO - Bus: Built-In 05:08:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:08:16 INFO - Vendor: Intel (0x8086) 05:08:16 INFO - Device ID: 0x0a2e 05:08:16 INFO - Revision ID: 0x0009 05:08:16 INFO - Displays: 05:08:16 INFO - Display: 05:08:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:08:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:08:16 INFO - Display Serial Number: 1600x1200 60 05:08:16 INFO - Main Display: Yes 05:08:16 INFO - Mirror: Off 05:08:16 INFO - Online: Yes 05:08:16 INFO - Rotation: Supported 05:08:16 INFO - Return code: 0 05:08:16 INFO - Running post-action listener: _resource_record_post_action 05:08:16 INFO - [mozharness: 2017-04-18 12:08:16.265604Z] Finished run-tests step (success) 05:08:16 INFO - Running post-run listener: _resource_record_post_run 05:08:16 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 1245184; Write bytes: 510108672; Read time: 6; Write time: 1571 05:08:16 INFO - TinderboxPrint: CPU usage
53.8% 05:08:16 INFO - TinderboxPrint: I/O read bytes / time
1,245,184 / 6 05:08:16 INFO - TinderboxPrint: I/O write bytes / time
510,108,672 / 1,571 05:08:16 INFO - TinderboxPrint: CPU idle
2,220.5 (46.2%) 05:08:16 INFO - TinderboxPrint: CPU system
960.3 (20.0%) 05:08:16 INFO - TinderboxPrint: CPU user
1,628.9 (33.9%) 05:08:16 INFO - TinderboxPrint: Swap in / out
209,022,976 / 0 05:08:16 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201560576; Write bytes: 200984064; Read time: 15758; Write time: 442 05:08:16 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 380928; Write bytes: 298335744; Read time: 3; Write time: 1097 05:08:16 INFO - Running post-run listener: _upload_blobber_files 05:08:16 INFO - Blob upload gear active. 05:08:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:16 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 05:08:16 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'] 05:08:16 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 05:08:17 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:08:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:17 INFO - (blobuploader) - INFO - Done attempting. 05:08:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:08:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:08:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:18 INFO - (blobuploader) - INFO - Done attempting. 05:08:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:08:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:08:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:19 INFO - (blobuploader) - INFO - Done attempting. 05:08:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:08:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:21 INFO - (blobuploader) - INFO - Done attempting. 05:08:21 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:21 INFO - Return code: 0 05:08:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52dc62e189bca10d1dfefa28e8885b5902972a509283b65291a1bebd45a227cde66053fb3302d6445255b5523392436e6fa0074e3d39d8b4f8c9dc7ec32e2738", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c95d1f9e41b7162e2368813d945f9e6fc0fb3dde0125461d33ac2d1f2815f963adc7fa73cce043e03d70d29609c0703fff9ab243d69be4d7415c867651bb850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e83ba8baef85c76a66a929d0616f45bcce731ea81df79aefb942936b863bf0d3fa36b08463a03fe7e1a895895729da7ed516b161d83449f48aac16ec52a3dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a42af2f4f48e66ba96d74fc24e181c57a723d4824e6f047c8c4a2b0d4ef2b4d4116fbd059275784d9afe40347467c573c8f49792ecd614a9935a840d2c4dd45"} 05:08:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:21 INFO - Contents: 05:08:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/52dc62e189bca10d1dfefa28e8885b5902972a509283b65291a1bebd45a227cde66053fb3302d6445255b5523392436e6fa0074e3d39d8b4f8c9dc7ec32e2738", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c95d1f9e41b7162e2368813d945f9e6fc0fb3dde0125461d33ac2d1f2815f963adc7fa73cce043e03d70d29609c0703fff9ab243d69be4d7415c867651bb850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e83ba8baef85c76a66a929d0616f45bcce731ea81df79aefb942936b863bf0d3fa36b08463a03fe7e1a895895729da7ed516b161d83449f48aac16ec52a3dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a42af2f4f48e66ba96d74fc24e181c57a723d4824e6f047c8c4a2b0d4ef2b4d4116fbd059275784d9afe40347467c573c8f49792ecd614a9935a840d2c4dd45"} 05:08:21 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:21 INFO - Copying logs to upload dir... 05:08:21 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.780806 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-04-18 05:08:22.049589) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 05:08:22.053152) ========= 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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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/52dc62e189bca10d1dfefa28e8885b5902972a509283b65291a1bebd45a227cde66053fb3302d6445255b5523392436e6fa0074e3d39d8b4f8c9dc7ec32e2738", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c95d1f9e41b7162e2368813d945f9e6fc0fb3dde0125461d33ac2d1f2815f963adc7fa73cce043e03d70d29609c0703fff9ab243d69be4d7415c867651bb850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e83ba8baef85c76a66a929d0616f45bcce731ea81df79aefb942936b863bf0d3fa36b08463a03fe7e1a895895729da7ed516b161d83449f48aac16ec52a3dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a42af2f4f48e66ba96d74fc24e181c57a723d4824e6f047c8c4a2b0d4ef2b4d4116fbd059275784d9afe40347467c573c8f49792ecd614a9935a840d2c4dd45"} build_url:https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://queue.taskcluster.net/v1/task/UQq0PMUQTi2AOefT4mMasw/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/52dc62e189bca10d1dfefa28e8885b5902972a509283b65291a1bebd45a227cde66053fb3302d6445255b5523392436e6fa0074e3d39d8b4f8c9dc7ec32e2738", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c95d1f9e41b7162e2368813d945f9e6fc0fb3dde0125461d33ac2d1f2815f963adc7fa73cce043e03d70d29609c0703fff9ab243d69be4d7415c867651bb850", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/54e83ba8baef85c76a66a929d0616f45bcce731ea81df79aefb942936b863bf0d3fa36b08463a03fe7e1a895895729da7ed516b161d83449f48aac16ec52a3dc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a42af2f4f48e66ba96d74fc24e181c57a723d4824e6f047c8c4a2b0d4ef2b4d4116fbd059275784d9afe40347467c573c8f49792ecd614a9935a840d2c4dd45"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 05:08:22.087860) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 05:08:22.088212) ========= 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.JOcjDYc2TK/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.Ofn5TAAip4/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005291 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 05:08:22.137419) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 05:08:22.137731) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 05:08:22.138064) ========= ========= Total master_lag: 0.25 =========