builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0134 starttime: 1492493329.24 results: success (0) buildid: 20170417205844 builduid: 75d48868276f474faa387cbae58f7a21 revision: 105e456d811b8c4616de688e9afdcf0af620b80b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.237366) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.237842) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.238144) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.264707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.265088) ========= 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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-17 22:28:49-- 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% 74.9M=0s 2017-04-17 22:28:49 (74.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.212377 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.503955) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.504550) ========= 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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.028287 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:49.557863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-17 22:28:49.558515) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 105e456d811b8c4616de688e9afdcf0af620b80b --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 105e456d811b8c4616de688e9afdcf0af620b80b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Y1iO9M288n/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.ZvpqBkSzFI/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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-17 22:28:49,663 truncating revision to first 12 chars 2017-04-17 22:28:49,663 Setting DEBUG logging. 2017-04-17 22:28:49,663 attempt 1/10 2017-04-17 22:28:49,663 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/105e456d811b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-17 22:28:51,035 unpacking tar archive at: mozilla-aurora-105e456d811b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.649132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-17 22:28:51.232636) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:51.232991) ========= script_repo_revision: 105e456d811b8c4616de688e9afdcf0af620b80b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:51.233455) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:51.233747) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 22:28:51.253769) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-17 22:28:51.254198) ========= /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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:28:51 INFO - MultiFileLogger online at 20170417 22:28:51 in /builds/slave/test 22:28: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 22:28:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:28:51 INFO - {'append_to_log': False, 22:28:51 INFO - 'base_work_dir': '/builds/slave/test', 22:28:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:28:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:28:51 INFO - 'branch': 'Mozilla-Aurora', 22:28:51 INFO - 'buildbot_json_path': 'buildprops.json', 22:28:51 INFO - 'config_files': ('talos/mac_config.py',), 22:28:51 INFO - 'default_actions': ('clobber', 22:28:51 INFO - 'read-buildbot-config', 22:28:51 INFO - 'download-and-extract', 22:28:51 INFO - 'populate-webroot', 22:28:51 INFO - 'create-virtualenv', 22:28:51 INFO - 'install', 22:28:51 INFO - 'run-tests'), 22:28:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:28:51 INFO - 'download_minidump_stackwalk': True, 22:28:51 INFO - 'download_symbols': 'ondemand', 22:28:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:28:51 INFO - 'tooltool.py': '/tools/tooltool.py', 22:28:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:28:51 INFO - '/tools/misc-python/virtualenv.py')}, 22:28:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:28:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:28:51 INFO - 'gecko_profile': False, 22:28:51 INFO - 'gecko_profile_interval': 0, 22:28:51 INFO - 'installer_path': 'installer.exe', 22:28:51 INFO - 'log_level': 'info', 22:28:51 INFO - 'log_name': 'talos', 22:28:51 INFO - 'log_to_console': True, 22:28:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:28:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:28:51 INFO - 'opt_config_files': (), 22:28:51 INFO - 'pip_index': False, 22:28:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:28:51 INFO - 'cmd': ('bash', 22:28:51 INFO - '-c', 22:28:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:28:51 INFO - 'enabled': True, 22:28:51 INFO - 'halt_on_failure': False, 22:28:51 INFO - 'name': 'check_screen_resolution'},), 22:28:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:28:51 INFO - 'cmd': ('bash', 22:28:51 INFO - '-c', 22:28:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:28:51 INFO - 'enabled': True, 22:28:51 INFO - 'halt_on_failure': False, 22:28:51 INFO - 'name': 'check_screen_resolution'},), 22:28:51 INFO - 'run_cmd_checks_enabled': True, 22:28:51 INFO - 'suite': 'dromaeojs', 22:28:51 INFO - 'system_bits': '32', 22:28:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:28:51 INFO - 'title': 't-yosemite-r7-0134', 22:28:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:28:51 INFO - 'use_talos_json': True, 22:28:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:28:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:28:51 INFO - 'work_dir': 'build'} 22:28:51 INFO - [mozharness: 2017-04-18 05:28:51.472128Z] Running clobber step. 22:28:51 INFO - Running pre-action listener: _resource_record_pre_action 22:28:51 INFO - Running main action method: clobber 22:28:51 INFO - rmtree: /builds/slave/test/build 22:28:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:28:53 INFO - Running post-action listener: _resource_record_post_action 22:28:53 INFO - [mozharness: 2017-04-18 05:28:53.935471Z] Finished clobber step (success) 22:28:53 INFO - [mozharness: 2017-04-18 05:28:53.935682Z] Running read-buildbot-config step. 22:28:53 INFO - Running pre-action listener: _resource_record_pre_action 22:28:53 INFO - Running main action method: read_buildbot_config 22:28:53 INFO - Using buildbot properties: 22:28:53 INFO - { 22:28:53 INFO - "project": "", 22:28:53 INFO - "product": "firefox", 22:28:53 INFO - "script_repo_revision": "production", 22:28:53 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 22:28:53 INFO - "repository": "", 22:28:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 22:28:53 INFO - "buildid": "20170417205844", 22:28:53 INFO - "pgo_build": "False", 22:28:53 INFO - "basedir": "/builds/slave/test", 22:28:53 INFO - "buildnumber": 31, 22:28:53 INFO - "slavename": "t-yosemite-r7-0134", 22:28:53 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:28:53 INFO - "platform": "yosemite_r7", 22:28:53 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 22:28:53 INFO - "branch": "mozilla-aurora", 22:28:53 INFO - "repo_path": "releases/mozilla-aurora", 22:28:53 INFO - "revision": "105e456d811b8c4616de688e9afdcf0af620b80b", 22:28:53 INFO - "stage_platform": "macosx64", 22:28:53 INFO - "builduid": "75d48868276f474faa387cbae58f7a21", 22:28:53 INFO - "slavebuilddir": "test" 22:28:53 INFO - } 22:28:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 22:28:53 INFO - Running post-action listener: _resource_record_post_action 22:28:53 INFO - [mozharness: 2017-04-18 05:28:53.938902Z] Finished read-buildbot-config step (success) 22:28:53 INFO - [mozharness: 2017-04-18 05:28:53.939039Z] Running download-and-extract step. 22:28:53 INFO - Running pre-action listener: _resource_record_pre_action 22:28:53 INFO - Running main action method: download_and_extract 22:28:53 INFO - mkdir: /builds/slave/test/build/tests 22:28: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')]} 22:28:53 INFO - trying https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 22:28:53 INFO - Downloading https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/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 22:28:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/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 22:28:54 INFO - Downloaded 1199 bytes. 22:28:54 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 22:28:54 INFO - Using the following test package requirements: 22:28:54 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 22:28:54 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 22:28:54 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 22:28:54 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'jsshell-mac.zip'], 22:28:54 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 22:28:54 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 22:28:54 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 22:28:54 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 22:28:54 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 22:28:54 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 22:28:54 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 22:28:54 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 22:28:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 22:28:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:28:54 INFO - Fetch https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 22:28:56 INFO - Expected file size: 51794722 22:28:56 INFO - Obtained file size: 51794722 22:29:12 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 22:29:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 22:29:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 22:29:12 INFO - Fetch https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 22:29:14 INFO - Expected file size: 51794722 22:29:14 INFO - Obtained file size: 51794722 22:29:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 22:29:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 22:29:29 INFO - Fetch https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 22:29:30 INFO - Expected file size: 14404424 22:29:30 INFO - Obtained file size: 14404424 22:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:29:30 INFO - trying https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 22:29:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 22:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/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 22:29:32 INFO - Downloaded 74746070 bytes. 22:29:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 22:29:32 INFO - mkdir: /builds/slave/test/properties 22:29:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:29:32 INFO - Writing to file /builds/slave/test/properties/build_url 22:29:32 INFO - Contents: 22:29:32 INFO - build_url:https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 22:29:33 INFO - Running post-action listener: _resource_record_post_action 22:29:33 INFO - Running post-action listener: set_extra_try_arguments 22:29:33 INFO - [mozharness: 2017-04-18 05:29:33.372811Z] Finished download-and-extract step (success) 22:29:33 INFO - [mozharness: 2017-04-18 05:29:33.372978Z] Running populate-webroot step. 22:29:33 INFO - Running pre-action listener: _resource_record_pre_action 22:29:33 INFO - Running main action method: populate_webroot 22:29:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:29:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:29:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:29:33 INFO - '--tppagecycles', 22:29:33 INFO - '7'], 22:29:33 INFO - 'tests': ['tsvgm']}}, 22:29:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:29:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:29:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:29:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:29:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:29:33 INFO - 'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:29:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:29:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:29:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:29:33 INFO - 'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['damp', 'tps']}, 22:29:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:29:33 INFO - 'tests': ['damp', 'tps']}, 22:29:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['dromaeo_dom']}, 22:29:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:29:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['basic_compositor_video']}, 22:29:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:29:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['a11yr', 22:29:33 INFO - 'ts_paint', 22:29:33 INFO - 'tpaint', 22:29:33 INFO - 'sessionrestore', 22:29:33 INFO - 'sessionrestore_no_auto_restore', 22:29:33 INFO - 'tabpaint']}, 22:29:33 INFO - 'other-e10s': {'tests': ['a11yr', 22:29:33 INFO - 'ts_paint', 22:29:33 INFO - 'tpaint', 22:29:33 INFO - 'sessionrestore', 22:29:33 INFO - 'sessionrestore_no_auto_restore', 22:29:33 INFO - 'tabpaint']}, 22:29:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:29:33 INFO - 'ts_paint', 22:29:33 INFO - 'tpaint', 22:29:33 INFO - 'sessionrestore', 22:29:33 INFO - 'sessionrestore_no_auto_restore', 22:29:33 INFO - 'tabpaint']}, 22:29:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:29:33 INFO - 'ts_paint', 22:29:33 INFO - 'tpaint', 22:29:33 INFO - 'sessionrestore', 22:29:33 INFO - 'sessionrestore_no_auto_restore', 22:29:33 INFO - 'tabpaint']}, 22:29:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['a11yr', 22:29:33 INFO - 'ts_paint', 22:29:33 INFO - 'tpaint', 22:29:33 INFO - 'sessionrestore', 22:29:33 INFO - 'sessionrestore_no_auto_restore', 22:29:33 INFO - 'tabpaint']}, 22:29:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['a11yr', 22:29:33 INFO - 'ts_paint', 22:29:33 INFO - 'tpaint', 22:29:33 INFO - 'sessionrestore', 22:29:33 INFO - 'sessionrestore_no_auto_restore', 22:29:33 INFO - 'tabpaint']}, 22:29:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['tsvgx', 22:29:33 INFO - 'tsvgr_opacity', 22:29:33 INFO - 'tart', 22:29:33 INFO - 'tscrollx', 22:29:33 INFO - 'cart', 22:29:33 INFO - 'tsvg_static']}, 22:29:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:29:33 INFO - 'tsvgr_opacity', 22:29:33 INFO - 'tart', 22:29:33 INFO - 'tscrollx', 22:29:33 INFO - 'cart', 22:29:33 INFO - 'tsvg_static']}, 22:29:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:29:33 INFO - 'talos_options': ['--disable-e10s'], 22:29:33 INFO - 'tests': ['tp5o']}, 22:29:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:29:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:29:33 INFO - 'talos_options': ['--disable-e10s', 22:29:33 INFO - '--xperf_path', 22:29:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:29:33 INFO - 'tests': ['tp5n']}, 22:29:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:29:33 INFO - 'talos_options': ['--xperf_path', 22:29:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:29:33 INFO - 'tests': ['tp5n']}}, 22:29:33 INFO - 'talos.zip': {'path': '', 22:29:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:29:33 INFO - Running post-action listener: _resource_record_post_action 22:29:33 INFO - [mozharness: 2017-04-18 05:29:33.391481Z] Finished populate-webroot step (success) 22:29:33 INFO - [mozharness: 2017-04-18 05:29:33.391624Z] Running create-virtualenv step. 22:29:33 INFO - Running pre-action listener: _resource_record_pre_action 22:29:33 INFO - Running main action method: create_virtualenv 22:29:33 INFO - Creating virtualenv /builds/slave/test/build/venv 22:29:33 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 22:29:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:29:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:29:33 INFO - Using real prefix '/tools/python27' 22:29:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:29:34 INFO - Installing distribute.............................................................................................................................................................................................done. 22:29:37 INFO - Installing pip.................done. 22:29:37 INFO - Return code: 0 22:29:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:29:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:29:37 INFO - Reading from file tmpfile_stdout 22:29:37 INFO - Output received: 22:29:37 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) 22:29:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:29:37 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')]} 22:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:37 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')]} 22:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:37 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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:29:37 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 22:29:37 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 22:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:37 INFO - 'HOME': '/Users/cltbld', 22:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:37 INFO - 'LOGNAME': 'cltbld', 22:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:37 INFO - 'MOZ_NO_REMOTE': '1', 22:29:37 INFO - 'NO_EM_RESTART': '1', 22:29:37 INFO - 'PAGER': '/bin/cat', 22:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:37 INFO - 'PWD': '/builds/slave/test', 22:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:37 INFO - 'SHELL': '/bin/bash', 22:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:29:37 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:37 INFO - 'USER': 'cltbld', 22:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:37 INFO - 'XPC_FLAGS': '0x0', 22:29:37 INFO - 'XPC_SERVICE_NAME': '0', 22:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:37 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 22:29:37 INFO - Cleaning up... 22:29:37 INFO - Return code: 0 22:29:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:29:37 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')]} 22:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:37 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')]} 22:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:37 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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:29:37 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 22:29:37 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 22:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:37 INFO - 'HOME': '/Users/cltbld', 22:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:37 INFO - 'LOGNAME': 'cltbld', 22:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:37 INFO - 'MOZ_NO_REMOTE': '1', 22:29:37 INFO - 'NO_EM_RESTART': '1', 22:29:37 INFO - 'PAGER': '/bin/cat', 22:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:37 INFO - 'PWD': '/builds/slave/test', 22:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:37 INFO - 'SHELL': '/bin/bash', 22:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:29:37 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:37 INFO - 'USER': 'cltbld', 22:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:37 INFO - 'XPC_FLAGS': '0x0', 22:29:37 INFO - 'XPC_SERVICE_NAME': '0', 22:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:37 INFO - Downloading/unpacking psutil>=3.1.1 22:29:37 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 22:29:37 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 22:29:37 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 22:29:37 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 22:29:37 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 22:29:37 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 22:29:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:29:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:29:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:29:41 INFO - Installing collected packages: psutil 22:29:41 INFO - Running setup.py install for psutil 22:29:41 INFO - building 'psutil._psutil_osx' extension 22:29:41 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 22:29:42 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 22:29:42 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 22:29:42 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 22:29:42 INFO - building 'psutil._psutil_posix' extension 22:29:42 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 22:29:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:29:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:29:42 INFO - ^ 22:29:42 INFO - 1 warning generated. 22:29:42 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 22:29:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:29:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:29:42 INFO - Successfully installed psutil 22:29:42 INFO - Cleaning up... 22:29:42 INFO - Return code: 0 22:29:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:29: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')]} 22:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29: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')]} 22:29:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:29: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:29: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 mozsystemmonitor==0.3 22:29:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:29:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:42 INFO - 'HOME': '/Users/cltbld', 22:29:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:42 INFO - 'LOGNAME': 'cltbld', 22:29:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:42 INFO - 'MOZ_NO_REMOTE': '1', 22:29:42 INFO - 'NO_EM_RESTART': '1', 22:29:42 INFO - 'PAGER': '/bin/cat', 22:29:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:42 INFO - 'PWD': '/builds/slave/test', 22:29:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:42 INFO - 'SHELL': '/bin/bash', 22:29:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:29:42 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:29:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:42 INFO - 'USER': 'cltbld', 22:29:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:42 INFO - 'XPC_FLAGS': '0x0', 22:29:42 INFO - 'XPC_SERVICE_NAME': '0', 22:29:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:29:42 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 22:29:42 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 22:29:42 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 22:29:42 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 22:29:42 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 22:29:42 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 22:29:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:29:45 INFO - Installing collected packages: mozsystemmonitor 22:29:45 INFO - Running setup.py install for mozsystemmonitor 22:29:45 INFO - Successfully installed mozsystemmonitor 22:29:45 INFO - Cleaning up... 22:29:45 INFO - Return code: 0 22:29:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:29:45 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')]} 22:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:46 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')]} 22:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:46 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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:29:46 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 22:29:46 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 22:29:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:29:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:46 INFO - 'HOME': '/Users/cltbld', 22:29:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:46 INFO - 'LOGNAME': 'cltbld', 22:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:46 INFO - 'MOZ_NO_REMOTE': '1', 22:29:46 INFO - 'NO_EM_RESTART': '1', 22:29:46 INFO - 'PAGER': '/bin/cat', 22:29:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:46 INFO - 'PWD': '/builds/slave/test', 22:29:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:46 INFO - 'SHELL': '/bin/bash', 22:29:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:29:46 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:29:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:46 INFO - 'USER': 'cltbld', 22:29:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:46 INFO - 'XPC_FLAGS': '0x0', 22:29:46 INFO - 'XPC_SERVICE_NAME': '0', 22:29:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:46 INFO - Downloading/unpacking jsonschema==2.5.1 22:29:46 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 22:29:46 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 22:29:46 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 22:29:46 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 22:29:46 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 22:29:46 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 22:29:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:29:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29: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 22:29:50 INFO - Downloading functools32-3.2.3-2.tar.gz 22:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:29:50 INFO - warning: no files found matching '*.txt' 22:29:50 INFO - no previously-included directories found matching 'build' 22:29:50 INFO - no previously-included directories found matching 'dist' 22:29:50 INFO - no previously-included directories found matching '.git*' 22:29:50 INFO - Installing collected packages: jsonschema, functools32 22:29:50 INFO - Running setup.py install for functools32 22:29:50 INFO - warning: no files found matching '*.txt' 22:29:50 INFO - no previously-included directories found matching 'build' 22:29:50 INFO - no previously-included directories found matching 'dist' 22:29:50 INFO - no previously-included directories found matching '.git*' 22:29:50 INFO - Successfully installed jsonschema functools32 22:29:50 INFO - Cleaning up... 22:29:50 INFO - Return code: 0 22:29:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:50 INFO - 'HOME': '/Users/cltbld', 22:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:50 INFO - 'LOGNAME': 'cltbld', 22:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:50 INFO - 'MOZ_NO_REMOTE': '1', 22:29:50 INFO - 'NO_EM_RESTART': '1', 22:29:50 INFO - 'PAGER': '/bin/cat', 22:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:50 INFO - 'PWD': '/builds/slave/test', 22:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:50 INFO - 'SHELL': '/bin/bash', 22:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:29:50 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:50 INFO - 'USER': 'cltbld', 22:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:50 INFO - 'XPC_FLAGS': '0x0', 22:29:50 INFO - 'XPC_SERVICE_NAME': '0', 22:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:29:50 INFO - Cleaning up... 22:29:50 INFO - Return code: 0 22:29:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:29:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:29:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:50 INFO - 'HOME': '/Users/cltbld', 22:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:50 INFO - 'LOGNAME': 'cltbld', 22:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:50 INFO - 'MOZ_NO_REMOTE': '1', 22:29:50 INFO - 'NO_EM_RESTART': '1', 22:29:50 INFO - 'PAGER': '/bin/cat', 22:29:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:50 INFO - 'PWD': '/builds/slave/test', 22:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:50 INFO - 'SHELL': '/bin/bash', 22:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:29:50 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:50 INFO - 'USER': 'cltbld', 22:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:50 INFO - 'XPC_FLAGS': '0x0', 22:29:50 INFO - 'XPC_SERVICE_NAME': '0', 22:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:51 INFO - Downloading/unpacking blobuploader==1.2.4 22:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:29:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:29:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:29:53 INFO - Downloading blobuploader-1.2.4.tar.gz 22:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:29:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:29:53 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 22:29:53 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 22:29:53 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 22:29:53 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 22:29:53 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 22:29:53 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 22:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:29:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:29:54 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 22:29:54 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 22:29:54 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 22:29:54 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 22:29:54 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 22:29:54 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 22:29:55 INFO - Downloading docopt-0.6.1.tar.gz 22:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:29:55 INFO - Installing collected packages: blobuploader, requests, docopt 22:29:55 INFO - Running setup.py install for blobuploader 22:29:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:29:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:29:55 INFO - Running setup.py install for requests 22:29:55 INFO - Running setup.py install for docopt 22:29:56 INFO - Successfully installed blobuploader requests docopt 22:29:56 INFO - Cleaning up... 22:29:56 INFO - Return code: 0 22:29:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:29: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')]} 22:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29: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')]} 22:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:29:56 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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:29:56 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 22:29:56 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 22:29:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:29:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:56 INFO - 'HOME': '/Users/cltbld', 22:29:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:56 INFO - 'LOGNAME': 'cltbld', 22:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:56 INFO - 'MOZ_NO_REMOTE': '1', 22:29:56 INFO - 'NO_EM_RESTART': '1', 22:29:56 INFO - 'PAGER': '/bin/cat', 22:29:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:56 INFO - 'PWD': '/builds/slave/test', 22:29:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:56 INFO - 'SHELL': '/bin/bash', 22:29:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:29:56 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:29:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:56 INFO - 'USER': 'cltbld', 22:29:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:56 INFO - 'XPC_FLAGS': '0x0', 22:29:56 INFO - 'XPC_SERVICE_NAME': '0', 22:29:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:29:56 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-pLvYhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:29:56 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-PNLT8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:29:56 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-6O6rma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:29:56 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-6x2dyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:29:56 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-JL3m5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:29:56 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ue_65K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-tadP8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-oa9ngd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-K0vcHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-b4_kjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-xg9Kks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-kpqvCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-0uvByV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-FutwfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-MbMNPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:29:57 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-wSrw1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:29:58 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-2IAuCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:29:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:29:58 INFO - Running setup.py install for manifestparser 22:29:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:29:58 INFO - Running setup.py install for mozcrash 22:29:58 INFO - Running setup.py install for mozdebug 22:29:58 INFO - Running setup.py install for mozdevice 22:29:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:29:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:29:58 INFO - Running setup.py install for mozfile 22:29:58 INFO - Running setup.py install for mozhttpd 22:29:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Running setup.py install for mozinfo 22:29:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Running setup.py install for mozInstall 22:29:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Running setup.py install for mozleak 22:29:59 INFO - Running setup.py install for mozlog 22:29:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Running setup.py install for moznetwork 22:29:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:29:59 INFO - Running setup.py install for mozprocess 22:29:59 INFO - Running setup.py install for mozprofile 22:30:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:30:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:30:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:30:00 INFO - Running setup.py install for mozrunner 22:30:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:30:00 INFO - Running setup.py install for mozscreenshot 22:30:00 INFO - Running setup.py install for moztest 22:30:00 INFO - Running setup.py install for mozversion 22:30:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:30:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:30:00 INFO - Cleaning up... 22:30:00 INFO - Return code: 0 22:30:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30:00 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')]} 22:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:00 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')]} 22:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:00 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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:30:00 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 22:30:00 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 22:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:30:00 INFO - 'HOME': '/Users/cltbld', 22:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:30:00 INFO - 'LOGNAME': 'cltbld', 22:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:00 INFO - 'MOZ_NO_REMOTE': '1', 22:30:00 INFO - 'NO_EM_RESTART': '1', 22:30:00 INFO - 'PAGER': '/bin/cat', 22:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:00 INFO - 'PWD': '/builds/slave/test', 22:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:30:00 INFO - 'SHELL': '/bin/bash', 22:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:30:00 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:30:00 INFO - 'USER': 'cltbld', 22:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:00 INFO - 'XPC_FLAGS': '0x0', 22:30:00 INFO - 'XPC_SERVICE_NAME': '0', 22:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-kmb2EI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-5dB96T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-E9esc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-W9m0U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-AArUxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-GY4Xs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-owBikU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-XZxcmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-0akTda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:30:01 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)) 22:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:30:01 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-_fYJmL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:30:02 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)) 22:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:30:02 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-HDfDY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:30:02 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)) 22:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:30:02 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-4NjA80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:30:02 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)) 22:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:30:02 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-8z76q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:30:02 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)) 22:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:30:02 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-jtEyei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:30:02 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)) 22:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:02 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-Qh_yyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:30:02 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)) 22:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:30:02 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-t7xLPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:30:02 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)) 22:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:30:02 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-eiHdeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:30:02 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)) 22:30:02 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)) 22:30:02 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)) 22:30:02 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)) 22:30:02 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)) 22:30:02 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)) 22:30:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:30:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:30:05 INFO - Downloading blessings-1.6.tar.gz 22:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:30:05 INFO - Installing collected packages: blessings 22:30:05 INFO - Running setup.py install for blessings 22:30:05 INFO - Successfully installed blessings 22:30:05 INFO - Cleaning up... 22:30:05 INFO - Return code: 0 22:30:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:06 INFO - Reading from file tmpfile_stdout 22:30:06 INFO - Current package versions: 22:30:06 INFO - blessings == 1.6 22:30:06 INFO - blobuploader == 1.2.4 22:30:06 INFO - docopt == 0.6.1 22:30:06 INFO - functools32 == 3.2.3-2 22:30:06 INFO - jsonschema == 2.5.1 22:30:06 INFO - manifestparser == 1.1 22:30:06 INFO - mozInstall == 1.12 22:30:06 INFO - mozcrash == 1.0 22:30:06 INFO - mozdebug == 0.1 22:30:06 INFO - mozdevice == 0.49 22:30:06 INFO - mozfile == 1.2 22:30:06 INFO - mozhttpd == 0.7 22:30:06 INFO - mozinfo == 0.9 22:30:06 INFO - mozleak == 0.1 22:30:06 INFO - mozlog == 3.4 22:30:06 INFO - moznetwork == 0.27 22:30:06 INFO - mozprocess == 0.25 22:30:06 INFO - mozprofile == 0.28 22:30:06 INFO - mozrunner == 6.13 22:30:06 INFO - mozscreenshot == 0.1 22:30:06 INFO - mozsystemmonitor == 0.3 22:30:06 INFO - moztest == 0.8 22:30:06 INFO - mozversion == 1.4 22:30:06 INFO - psutil == 3.1.1 22:30:06 INFO - requests == 1.2.3 22:30:06 INFO - wsgiref == 0.1.2 22:30:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:30: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')]} 22:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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')]} 22:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:06 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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:30:06 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 22:30:06 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 22:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:30:06 INFO - 'HOME': '/Users/cltbld', 22:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:30:06 INFO - 'LOGNAME': 'cltbld', 22:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:06 INFO - 'MOZ_NO_REMOTE': '1', 22:30:06 INFO - 'NO_EM_RESTART': '1', 22:30:06 INFO - 'PAGER': '/bin/cat', 22:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:06 INFO - 'PWD': '/builds/slave/test', 22:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:30:06 INFO - 'SHELL': '/bin/bash', 22:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:30:06 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:30:06 INFO - 'USER': 'cltbld', 22:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:06 INFO - 'XPC_FLAGS': '0x0', 22:30:06 INFO - 'XPC_SERVICE_NAME': '0', 22:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 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)) 22:30:06 INFO - Cleaning up... 22:30:06 INFO - Return code: 0 22:30:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:30: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')]} 22:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30: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')]} 22:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:30:06 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 0x10949bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10949e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fada8d626d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, '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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:30:06 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 22:30:06 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 22:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:30:06 INFO - 'HOME': '/Users/cltbld', 22:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:30:06 INFO - 'LOGNAME': 'cltbld', 22:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:06 INFO - 'MOZ_NO_REMOTE': '1', 22:30:06 INFO - 'NO_EM_RESTART': '1', 22:30:06 INFO - 'PAGER': '/bin/cat', 22:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:06 INFO - 'PWD': '/builds/slave/test', 22:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:30:06 INFO - 'SHELL': '/bin/bash', 22:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:30:06 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:30:06 INFO - 'USER': 'cltbld', 22:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:06 INFO - 'XPC_FLAGS': '0x0', 22:30:06 INFO - 'XPC_SERVICE_NAME': '0', 22:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:30:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:30:06 INFO - Cleaning up... 22:30:06 INFO - Return code: 0 22:30:06 INFO - Running post-action listener: _resource_record_post_action 22:30:06 INFO - Running post-action listener: _start_resource_monitoring 22:30:06 INFO - Starting resource monitoring. 22:30:06 INFO - [mozharness: 2017-04-18 05:30:06.685688Z] Finished create-virtualenv step (success) 22:30:06 INFO - [mozharness: 2017-04-18 05:30:06.686450Z] Running install step. 22:30:06 INFO - Running pre-action listener: _resource_record_pre_action 22:30:06 INFO - Running main action method: install 22:30:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:30:06 INFO - Reading from file tmpfile_stdout 22:30:06 INFO - Detecting whether we're running mozinstall >=1.0... 22:30:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:30:07 INFO - Reading from file tmpfile_stdout 22:30:07 INFO - Output received: 22:30:07 INFO - Usage: mozinstall [options] installer 22:30:07 INFO - Options: 22:30:07 INFO - -h, --help show this help message and exit 22:30:07 INFO - -d DEST, --destination=DEST 22:30:07 INFO - Directory to install application into. [default: 22:30:07 INFO - "/builds/slave/test"] 22:30:07 INFO - --app=APP Application being installed. [default: firefox] 22:30:07 INFO - mkdir: /builds/slave/test/build/application 22:30:07 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'] 22:30:07 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 22:30:27 INFO - Reading from file tmpfile_stdout 22:30:27 INFO - Output received: 22:30:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 22:30:27 INFO - Running post-action listener: _resource_record_post_action 22:30:27 INFO - [mozharness: 2017-04-18 05:30:27.680207Z] Finished install step (success) 22:30:27 INFO - [mozharness: 2017-04-18 05:30:27.680340Z] Running run-tests step. 22:30:27 INFO - Running pre-action listener: _resource_record_pre_action 22:30:27 INFO - Running main action method: run_tests 22:30:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:30:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:30:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:30:27 INFO - 2017-04-17 22:30:27.695 screenresolution[1628:7376] starting screenresolution argv=screenresolution get 22:30:27 INFO - 2017-04-17 22:30:27.697 screenresolution[1628:7376] Display 0: 1600x1200x32@60 22:30:27 INFO - 2017-04-17 22:30:27.703 screenresolution[1629:7379] starting screenresolution argv=screenresolution list 22:30:27 INFO - Available Modes on Display 0 22:30:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:30:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:30:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:30:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:30:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:30:27 INFO - Intel Iris: 22:30:27 INFO - Chipset Model: Intel Iris 22:30:27 INFO - Type: GPU 22:30:27 INFO - Bus: Built-In 22:30:27 INFO - VRAM (Dynamic, Max): 1536 MB 22:30:27 INFO - Vendor: Intel (0x8086) 22:30:27 INFO - Device ID: 0x0a2e 22:30:27 INFO - Revision ID: 0x0009 22:30:27 INFO - Displays: 22:30:27 INFO - Display: 22:30:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:30:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:30:27 INFO - Display Serial Number: 1600x1200 60 22:30:27 INFO - Main Display: Yes 22:30:27 INFO - Mirror: Off 22:30:27 INFO - Online: Yes 22:30:27 INFO - Rotation: Supported 22:30:27 INFO - Return code: 0 22:30:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:30:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:30:27 INFO - Python 2.7.3 22:30:27 INFO - Return code: 0 22:30:27 INFO - grabbing minidump binary from tooltool 22:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:30:27 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 0x109279d30>, '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 0x1091a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10926ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:30:27 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 22:30:27 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 22:30:27 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 22:30:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:30:28 INFO - Return code: 0 22:30:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:30:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:30:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:30:28 INFO - ENV: RUST_BACKTRACE is now 1 22:30:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:30:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:30:28 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/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 22:30:28 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/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --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 22:30:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Y1iO9M288n/Render', 22:30:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:30:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:30:28 INFO - 'HOME': '/Users/cltbld', 22:30:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:30:28 INFO - 'LOGNAME': 'cltbld', 22:30:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:30:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:30:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:30:28 INFO - 'MOZ_NO_REMOTE': '1', 22:30:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:30:28 INFO - 'NO_EM_RESTART': '1', 22:30:28 INFO - 'PAGER': '/bin/cat', 22:30:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:30:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:30:28 INFO - 'PWD': '/builds/slave/test', 22:30:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:30:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:30:28 INFO - 'RUST_BACKTRACE': '1', 22:30:28 INFO - 'SHELL': '/bin/bash', 22:30:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvpqBkSzFI/Listeners', 22:30:28 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:30:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:30:28 INFO - 'USER': 'cltbld', 22:30:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:30:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:30:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:30:28 INFO - 'XPC_FLAGS': '0x0', 22:30:28 INFO - 'XPC_SERVICE_NAME': '0', 22:30:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:30:28 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/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 22:30:28 INFO - mozversion application_buildid: 20170417205844 22:30:28 INFO - mozversion application_changeset: 105e456d811b8c4616de688e9afdcf0af620b80b 22:30:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:30:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:30:28 INFO - mozversion application_name: Firefox 22:30:28 INFO - mozversion application_remotingname: firefox-dev 22:30:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:30:28 INFO - mozversion application_vendor: Mozilla 22:30:28 INFO - mozversion application_version: 54.0a2 22:30:28 INFO - mozversion platform_buildid: 20170417205844 22:30:28 INFO - mozversion platform_changeset: 105e456d811b8c4616de688e9afdcf0af620b80b 22:30:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:30:28 INFO - mozversion platform_version: 54.0a2 22:30:28 INFO - using testdate: 1492493428 22:30:28 INFO - actual date: 1492493428 22:30:28 INFO - starting webserver on 'localhost:49244' 22:30:28 INFO - SUITE-START | Running 2 tests 22:30:28 INFO - TEST-START | dromaeo_css 22:30:28 INFO - operating with platform_type : mac_ 22:30:28 INFO - Initialising browser for dromaeo_css test... 22:30:28 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpftEEVn/profile http://localhost:49244/getInfo.html) 22:30:30 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 22:30:30 INFO - PROCESS | 1639 | colorDepth:24 22:30:30 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 22:30:30 INFO - PROCESS | 1639 | __metrics 22:30:30 INFO - PROCESS | 1639 | 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" 22:30:30 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:30:31 INFO - TEST-INFO | 1639: exit 0 22:30:31 INFO - Browser initialized. 22:30:31 INFO - Running cycle 1/1 for dromaeo_css test... 22:30:31 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpftEEVn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:43 INFO - PROCESS | 1641 | RSS: Main: 214949888 22:30:43 INFO - PROCESS | 1641 | 22:33:48 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 22:33:50 INFO - PROCESS | 1641 | RSS: Main: 369295360 22:33:50 INFO - PROCESS | 1641 | 22:35:33 INFO - PROCESS | 1641 | ************************* 22:35:33 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 22:35:33 INFO - PROCESS | 1641 | 22:35:33 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:35:33 INFO - PROCESS | 1641 | Full stack: 22:35:33 INFO - PROCESS | 1641 | ************************* 22:35:33 INFO - PROCESS | 1641 | ************************* 22:35:33 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 22:35:33 INFO - PROCESS | 1641 | 22:35:33 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:35:33 INFO - PROCESS | 1641 | Full stack: 22:35:33 INFO - PROCESS | 1641 | ************************* 22:35:33 INFO - PROCESS | 1641 | ************************* 22:35:33 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 22:35:33 INFO - PROCESS | 1641 | 22:35:33 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:35:33 INFO - PROCESS | 1641 | Full stack: 22:35:33 INFO - PROCESS | 1641 | ************************* 22:35:33 INFO - PROCESS | 1641 | ************************* 22:35:33 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 22:35:33 INFO - PROCESS | 1641 | 22:35:33 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:35:33 INFO - PROCESS | 1641 | Full stack: 22:35:33 INFO - PROCESS | 1641 | ************************* 22:36:52 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 22:36:53 INFO - PROCESS | 1641 | RSS: Main: 350957568 22:36:53 INFO - PROCESS | 1641 | 22:39:55 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 22:39:56 INFO - PROCESS | 1641 | RSS: Main: 346890240 22:39:56 INFO - PROCESS | 1641 | 22:42:59 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 22:42:59 INFO - PROCESS | 1641 | RSS: Main: 326991872 22:42:59 INFO - PROCESS | 1641 | 22:46:03 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 22:46:04 INFO - PROCESS | 1641 | RSS: Main: 317980672 22:46:04 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 22:49:07 INFO - PROCESS | 1641 | RSS: Main: 444579840 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | __start_tp_report 22:49:07 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 22:49:07 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 22:49:07 INFO - PROCESS | 1641 | |i|pagename|runs| 22:49:07 INFO - PROCESS | 1641 | |0;dojo.html;3427.7600567960253;3439.1058324835544;3446;3451.0682115828727;3484.9651503484956;6013.398660133985;6033.306169790475;6044.727987240573;6084.665343406116;6088.756449742011;5992.460678538931;6058.727357268923;6067.908981365283;6081.0878368244785;6081.118237855509;5366.356037275523;5399.325084364455;5413.837584872441;5416.620836541444;5423.83728488144;5271.103912334893;5279.472052794709;5339.813106541272;5423.267858839065;5501.972490137544;5379.273798037254;5397.298351214336;5402;5411.377691565462;5414.972925135369;5384.6499977501335;5393.946060539422;5397.892042159174;5402.135658294694;5415.620906536544;5251.396089449724;5331.1470164773455;5354.357477102733;5357.598180136505;5363.168708850135;5935.55483338747;5958.523318134538;5961.940380596182;5966.134910438005;5968;6327.588706734094;6361.936380636181;6366.981282994699;6369.585976911486;6372.203474565679;5618.932402843447;6105.884339886945;6322.9051564226575;6357.777477788255;6404.071409645622;5707.809293663565;6125.050617154348;6131.632102073845;6133.622130947118;6134.294556126013;6267.62394256346;6623.503237257177;6631.27056023837;6648.900266496006;6654.833629159213;6262.09199666055;6271.216097987752;6284.717187726564;6342.651154186472;6373.330800265998;6382.170317858649;6428.421442070143;6439.195100612424;6442.581232219891;6472.870542589122;6346.619202847844;6354.904676429857;6355.650439225795;6355.968220158869;6366.076918846741;6158.753649854045;6174.475169610543;6266;6286.528510361742;6361.268454127742;4301.956980430155;5145.845624631267;5161.483851614808;5189.169732842727;5200.2199670049795;4558.1795276849825;4562.749048802348;4564.3153526970555;4588.898664320539;4612.838550650711;5772.422757724194;5837.211976383134;6007.128966299862;6069.453749162508;6070.453659170696;6324.6521441321165;6348.936510634927;6367.363263673596;6375.74497020114;6379.521535884827;6030.879382412327;6057.242844644419;6089.786857460056;6098.756049758049;6127.908081378783;5670.489655930904;5690.089585666274;5691.516221121168;5696.3733989261145;5698.145278208219;6265.122882796503;6304.5271604628915;6529.804105876831;6548.541602087808;6605.207375115017;6387.55287129915;6421.903671444838;6430.807075787734;6437.710303036468;6438.163038804925;6582.078509008838;6658.467322614276;6658.7336506539195;6663.26704062543;6667.399934005769;6275;6319;6332.986722124622;6338.683065846597;6352.174217351715;6431.29255781873;6438.8390290243115;6455.838604034937;6462.838429039311;6472.805815825533;6271.78048768291;6301.527385446018;6337.429631333202;6361.236651601653;6368.840778980377;6068.969655151696;6136.417040381157;6136.825445958321;6234.937650623436;6349.952257877399;6163.3528479509005;6230.314665386713;6242.812715618538;6243.750249989949;6244.594101383395;5244.501772331622;6331.176946997045;6334.429901308904;6339.936600633934;6348.746050157942;6001.159837622649;6046.667433291212;6077.574569780073;6136;6140.197513349842 22:49:07 INFO - PROCESS | 1641 | |1;ext.html;713.7430525010996;755.7883792538088;789.9605019749011;883.5935469683945;896.3456676626058;27700.307492312677;28099.29751756207;39791.2041759165;40162.39756403649;42321.78839105808;41218.55735049274;41402.757917262534;41438.17123657533;41524;41632.79183604078;9191.356605037634;9342.673006444777;9349.252059835197;9349.39228950116;9367.484788336638;7207.49547531673;7374.852502949964;10885.510152043176;11080.612178573752;11198.160137989642;11075.16936229786;11078.335299882034;11094.334339939589;11096.55613775448;11120.777584448306;10999.395033273167;11024.283421577651;11051.778964420706;11055.613053543088;11062.115030797517;7238.782836514913;7474.028376311099;10637.9468102659;10846.891531084746;10890.346579205274;38505.80747096275;38821.805890970645;38863.8340849775;38872;38876.63931762375;3858.4405261237243;3877.1664092220026;3910.843566257346;3928.764274143532;3965.306071437487;26871.731282687117;27983.72016279852;28664.713352866413;28916.71083289161;29126.126216213546;2382.9395918816135;2902.085842959461;2946.8379239141836;2951.1736713720193;2951.74910132639;303202;354173;354354;354383.22808386054;355103;53169;53209.467905321224;53248.73375633097;53325.2001219982;53466.7326663372;45235;55398.89202216014;55533.72233138808;55542.44457555372;55564;14480.348384322731;14897.553073407815;14900.552983410515;14907.031042982171;14943.40226390954;12873.420696068699;12888.097833151589;12898.871011289955;12909.54816581415;12934.935325323313;10324.328918620266;10727.83908241377;10802.135829133807;11874.821877671842;12037.277763334228;5464.26232458621;5516.538117398893;5521.1442226454965;5522.972385138049;5529.640573362719;804.6861723927556;877.2280393253895;946.9005754395689;966.3235734985398;1021.4688362051692;28306.85846570754;28666.42667146687;28974.855125724658;29036.564451533242;29760.404791904042;1046.7540128069897;1051.5793682526898;1078.412265315405;1083.8970297821695;1087.5541028178409;14940.402383904739;15051;15132.761427739099;15245.542633721006;15302.846971530365;468821;469518;470770;472355;472504;56702;56845.57886052799;56856.71571642198;56904;56935.71532142396;1418.7446259673152;1474.3266392644425;1474.3439169569438;1478.209158100411;1480.25247250138;26766.59850102289;28230.85884570564;28849.567256491588;28898.85550572234;28982;37321.626783731816;45569.772151139034;45646;45708.08583828371;45719;443576.7821160873;444659;444908;444989;445009;33190;33560.49659255064;33956.830215848764;34073.48889766705;34112.31775364429;33782.15544611404;43499.56500434955;44098.77950610226;44512.66462006145;46045.53954460412;29798.10605681833;29847.552286715283;30256.565887731762;30901.072967811;31105.844470778407;29932.85033574905;30053.549196762502;30652.84673576618;30889.382212355176;30951.690483095783;35498.11254718652;35637.64362356447;36427.08932276714;36807.711730089315;36922.44616330703;30192.54711179378;30283.69716302897;30479.695203047686;31330.373392531565;31506.684933151293;23277.3016809496;23679.28962131139;24593.87703061473;25113.24660260195;25139 22:49:07 INFO - PROCESS | 1641 | |2;jquery.html;407.82871194098476;414.6869113819066;422.56686895931665;431.0925501818672;446.3171347837807;18446.40295142448;19423.805761942378;20768.584628307424;20933.895330523344;20949;17456.476305710818;21620;22036.33890983273;22088.33734987949;22246.777532224674;6343.714532846033;6805.761798337059;6815.625140617289;6819.556728812611;6822.351876571718;6495.415412612864;6637.900431493532;6691.6319602421845;6718.764843230488;6719.160104986877;6390.584612000228;6715.227748808877;6726.69729862155;6730.4279136273235;6734.225564060122;6636.369544893228;6681.7995460136035;6730.427913627347;6738.966305168443;6749.2238392584495;6232.283287421959;6386.552941294111;6388.24562804442;6479.967600162016;6571.2771595124495;26111.869440652674;26191.9523219071;26221.34446638849;26296.86851565749;26554.20337389862;2866.125831621355;2893.6093627360247;2893.956590651142;3029.712177343149;3041.558973948765;15980.840191598052;16004.119773412458;16031.35874565016;16241.593960150973;17314.91342543279;2117.5976564452703;2135.1139277199945;2151.08578853988;2216.8780717060554;2224.2659922225625;108865;125014.37492812569;127933;128934.3553282237;129755.35122324423;20451.897740511202;20477.79522204759;20489.025338410294;20689.89655051715;20707.482312941996;33508.49737253943;33859.492107618404;33869.66130338714;34014.48978265328;34051.82974085163;8426.452280601741;10368.533415996148;10563.94718026405;10571.365718056788;10806.406462448957;7695.345895598824;8080.232377924088;8554.529500877512;8591.44155629882;8785;9749.80758273949;9936.950315248521;9941.403515789076;9948.751281218027;10064.144547713526;4286.957130428718;4330.848420305274;4347.804348804311;4368.279233926367;4373.300271956472;1020.6935123042517;1063.5905176506947;1067.887871773468;1079.9902091544275;1119.2445099557765;19833.900830496044;20402.89798550998;20481.692774608393;21290.680639790415;21513.569728605653;1071.223363061774;1082.377632861108;1101.2786624760795;1127.4193790198055;1130.3161587239765;10699.197560183018;10950.945245273882;10991.340519568852;11020.724481888017;11079.390633515073;421165;608872;757307.2134639291;795498;795777;167413;169036;170996;172477.68805779706;180210;1248.2510493703705;1250.412306216077;1304.9347532623522;1305.2560040776857;1310.9737805243842;18800;19114.33099841499;19466.61066778628;19537;19573.119209635886;63449;63553;64504;64577.67711161602;65456.67271663611;90363.54818225867;90609;92234.53882730543;93775.59336610093;93955;10283.537240824331;10796.244262901522;11346.94326528362;11541.596044138414;11762.882371176522;8001.319887809602;12135.393230338625;12595.24428534291;13025.153365031243;13180.934095329461;11354.205205635526;11431;11557.7110572233;11620.6513804586;11675.474603643026;10587.20595955291;10668.733281668021;10914.290571112851;10995.945020274847;11053.336799791716;11428.25716328436;11682.76634467323;11842.703932401759;11912.463939122586;11988.940055299669;11136.554537818396;11429;11731.41342932867;11908.642740717793;12003.159778815749;11169.106471482424;11332.433378331216;11352.375619341015;11417.200795944203;11448.855114488484 22:49:07 INFO - PROCESS | 1641 | |3;mootools.html;1346.6566025663453;1451.4194322271092;1554.3704799556176;1584.8573628373451;1584.8732101431888;9197;9651.4691691957;9662.75843103922;9754.804903901917;9757.7560560986;9562.617495300197;10250.897491025087;10580.947095264513;10623.36259824411;10773.515191816368;2350.7296660883962;2389.95220095598;2395.592749232626;2398.508305797316;2400.2079313826393;1971.7633883933947;2245.3264020793695;2381.880905954704;2427.854328740272;2433.707955045397;2009.939701808948;2115.4605575578285;2115.7249557557498;2116.0795054151495;2117.100232401231;2099.149844313048;2123.872567645946;2338.847974881627;2420.1408499982604;2427.235420842429;2420.45539753554;2422.6796395964243;2424.842385244953;2426.3085020768995;2427.4416884116754;11335.319880807177;11356.659300220925;11361.602343918006;11365.772684546346;11390.71523211918;4881.072596206709;5096.464871188509;5107.157319042353;5132.948670513284;5137.588992880598;3225.0969728476066;3539.7876127432437;3563.6614521620404;3669.7614655047337;3722.5532936047575;1214.3017764785284;1227.4169769359482;1236.9567065152767;1250.7060840702427;1806.530302121458;15428.305726242346;15524.301406436625;15534.223288835627;15565.766513502307;15574.065556066558;4691.132140554008;4705.976470117696;4708.152532544175;4735.621150307967;4781.832635857728;9738.902610973799;11470.48382822775;11497.942510287396;11498;11498.25261358009;5702.7718891243885;5709.74306156224;5713.05734553794;5719.370869204384;5725.799597014085;4571.862844114682;5049.141645920203;5172.8965420691375;5252.501012403815;5359.490848369399;3798.9810050950123;3881.204353107606;3882.320593896057;3923.5684074751753;3938.232044751246;1016.9373005209574;1028.5628607841638;1045.4354648489882;1046.3617193511952;1054.4780333734698;696.3002182806247;780.5043797188713;812.2364976921792;821.8356328734277;829.266099501933;6466.579672321284;6761.93238067613;6836.111305530249;7016.824579385454;7061.364477197077;974.7124598243536;1005.818952588541;1008.7793769538794;1010.4190090697878;1022.0494939705997;4114.794260286973;4117.670586353084;4118.588141185917;4175.436316097349;4257.084726783756;13219.405126769318;13781.793273100911;13784.241866697228;13786.173834363932;13806.792898106536;12401.689957751129;12637.115401921777;12726.109172357847;13674.179549227078;13729.519466818812;9869.75325616851;10152.695419137437;10156.085952264331;10179.338343007828;10193.184545236363;6276.058591211355;6545.770898018546;6636.867262654817;6647;6765.5264131512295;11953.94023029914;12045.939770301093;12195.207311524722;12207.511699531919;12394.194377365442;12130.696732581757;12509;12529.624111276677;12536.623901282976;12573.74852502963;9032.954835225783;9381.906180938104;9385.530723463937;9390.95304523473;9393.389429687322;10140.49297535135;10209.795804083727;10354.326968747007;10359.32664376813;10377.11794497446;8539.231469167804;8676.392652514263;8681.522516261666;8737.606807693543;8881.73354799357;7949.284564389232;8445.66217351299;8531.274841638531;8636;8748.47509149453;7225.9277407227355;7363.705451781869;7437.256274372519;7470;7470;5111.437741848319;5829.883402331845;5922.851928701817;6094.055421409688;6094.177286066324;5896.380880007709;5926.644401335933;5989.251343582052;5989.2812862456785;5993.0710739835395 22:49:07 INFO - PROCESS | 1641 | |4;prototype.html;347.51695143750186;371.33716316375273;375.80645967326814;382.069660376982;387.2970558436436;4373.4970478395;4375.278079116942;4996.4503904570465;5038.370203724535;5108.2848401223855;4228.89427764306;4294.227039132955;4309.461317335333;4340.978295108528;4935.531124543171;3729.627037296262;4033.8705162554384;4239.6396306313845;4263.275243208646;4276.075765905347;3304.8678052877976;3899.278633452806;3910.6284902934176;4054.5738850671137;4205.747655140702;3877.9224415511812;4182.97908510457;4199.370094485821;4206.1377417629465;4261.424707664472;3764.73646844721;4204.663626909855;4221.261279276145;4224.514180869178;4254.680898932577;3470.583529976394;3757.849686012556;3825.407061905409;3885.203533275672;3963.6829053675638;4151.730137541081;4365.672574556921;4421.336799480072;4864.513548645143;4868.853934381974;4136.255474014676;4409.559044095565;4677.532246775329;4804.303376010494;4918.262260660929;4309.4397728295435;5243.737813109329;5379.767396530435;5437.673739575638;5440.830221502357;3918.5297764268184;4133.940883746036;4366.039471316305;4375.649948004152;4376.518582955872;4811.687240329367;5459.2629994950175;5472.890542189134;5753.395893431171;5798;3966.345552983725;4001.0019695578258;4192.972721683207;4201.75206264653;4209.410682504452;4045.8240281379562;4304.784760762012;4473.865784026484;4481.327800829901;4508.571685689854;4239.554846741048;4882.902341953141;4894.902101957941;4908.828191013297;4909.435414927258;4823.998040813093;5034.84895453137;5302.5683065572075;5363.490468405495;5449.302595215394;1504.751715966875;1519.6352875309847;1525.8174914441174;1532.3793863485307;1536.9001014933995;1003.6437064842022;1096.1803406345427;1099.312929419113;1149.230015889356;1161.5411912294596;4956.801727930914;4958.553730164303;4968.826091086795;4980.808444284576;5042.092423363756;5040.950225113754;5571.9442805572235;5581.609287349846;5610.831675049755;5612.85967850807;4810.95189048112;4896.36347274852;4931.013797240566;5013.022460620144;5053.747312634354;2584.7286034966432;2641.564141916601;2712.9826315131827;2857.0428906316615;2896.985515072411;4899.447900272274;5163.07064728345;5784.681842498703;5794.362620111784;5842.474177324061;4906.730129842822;5352.678839269656;5461.2627295315415;5674.687892165971;5852.356240813507;5249.740062385;5511.972440137773;5764.962306784819;5813.47114005409;5830.591858569859;4876.854673527615;5235.764390602356;5551.084071128219;5562.276904002454;5586.581006424616;5061.443241243386;5521.355905102613;5538.723063846711;5562.944370556243;5735.71321433935;4675.883102922318;5084.161113416245;5088.389393272831;5329.493698098674;5813.418658134153;4153.460050193456;4260.105377870682;4351.521332653342;4381.956180438155;4389.38831395332;5287.471252874681;5475.35739278207;5659.801906933238;5696.718238396327;5709.771609135588;4623.606993405599;4750.674527830602;4918.532739389752;5143.485651434827;5351.785928562814;4797.481322305678;4854.063291708466;5410.323709536308;5421.969825733098;5428.45715428454;4622.6764126510825;5254.97372513135;5256.948610277883;5304.867378315573;5447.22191682237;4826;5005.674631148963;5344.225087362389;5362.115250983544;5448.509634132947;4788.4253889533475;5238.1142788596835;5281.260623512788;5316.494932981361;5435.483629055233 22:49:07 INFO - PROCESS | 1641 | |5;yui.html;714.1251966341235;731.2322061835075;772.3975299266571;817.5421763812266;825.7440193540003;15131.243437828105;15851.207439628015;15866.682666346665;15889.841101588981;15932.681346373065;14520.3465844037;14530;14549.345279462423;14621.780673289883;14679.192644404553;860.496609483453;913.9634414623424;924.4360939826701;932.6922115701836;936.7002559181064;948.042477098129;964.3972517176765;966.0677446264353;966.5119114847028;967.9274054445945;1014.8426993815971;1017.4200705597813;1017.959281628734;1018.221060888421;1023.2019025160387;958.9376690515094;1009.8990100989915;1011.9747006324827;1013.2552573858209;1013.9898601014006;754.1817128415676;755.1881727143343;756.315534441326;756.4440136499668;757.0272200222743;15716.449924252596;15964.521064368088;16051.759223611654;16382.918085409616;16634.833651663448;12744;14086.436542538286;14220.360083796151;14389.856101438956;14502.56492305222;9882.802343953153;11729.178957472981;11937;12062.577809776703;12100.939495302468;3533.575970883485;3612.3678356287805;3660.853565857389;3780.9621903780885;3794.6964242860504;112229;113788;115123.42438287838;115448;117973;10254.384736915808;10752.301100428447;11036.11711063129;11116.166287528467;11237.887621123848;18496.722549161772;21698.56602867934;22039.559208815735;22193.22323718662;22235.11059557632;7360.374368178657;7360.558366498028;7437.665305061285;7745.419093568005;7825;4265.722728022669;4273.1240095780295;4357.9782101089295;4371.890702732457;4466.553344665507;3317.800931944091;3417.863285468603;3430.2281986552825;3438.638942911008;3442.5180474733556;516.161237988269;522.1384715219917;522.6550476685369;538.9649672771258;541.6993568569403;490.63202598051464;527.7702952121548;530.7691154347793;539.2127493858932;568.0456832521404;9099.772505687279;9135.862962055575;9590.904090959;9745.220382369393;9904.108630223314;633.1104797759156;650.677914432359;653.9967300163469;675.864827034595;683.4361651637419;3657.634236576321;4654.976725116353;4664.090502352076;4671.883202919954;4672.112298663243;97204;97211.0278897216;98377.50811245898;98450.01549984551;100332.49833750784;56772;71139;73405;79820.60089699514;79912;72964;73671;75824.62087689526;75860.62069689616;77085.0728731775;10827.837582436112;11066.778664426505;11090.667279981613;11152.944235278772;11315.377654229096;44632.884177895816;44672;44896.55103448924;45236;46386.53613463822;45970.54029459663;57917.42082579289;58780.70609646924;59004.40995589989;69853;12537.373131343214;13043.804342934674;13257.817485641292;13263.602091937257;13524.323783810967;13419.396127174105;14897.925510372379;15142.924285378944;15433;15468.845311547191;13427;13671.17972921628;13742.93128534351;13839.861601383856;13977;13945.163290202214;14073.718525629634;14421.4952476667;14430.494932677306;14762.261886905826;13178.538751144048;13855.445782168601;13896.374663140385;14364.425422982964;14366.640833979234;8240.423170378015;8704.738857834274;9140.223081038186;9186.31102667316;9195.816083678423;10690.679279621623;11470.885291146982;11485.942570287096;11586.942065289619;11611.6516504505 22:49:07 INFO - PROCESS | 1641 | __end_tp_report 22:49:07 INFO - PROCESS | 1641 | __start_cc_report 22:49:07 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1505 22:49:07 INFO - PROCESS | 1641 | __end_cc_report 22:49:07 INFO - PROCESS | 1641 | __startTimestamp1492494547812__endTimestamp 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | ------- Summary: start ------- 22:49:07 INFO - PROCESS | 1641 | Number of tests: 6 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5932.92 Median:6136.00 stddev:661.28 (10.8%) stddev-sans-first:633.43 22:49:07 INFO - PROCESS | 1641 | Values: 3427.8 3439.1 3446.0 3451.1 3485.0 6013.4 6033.3 6044.7 6084.7 6088.8 5992.5 6058.7 6067.9 6081.1 6081.1 5366.4 5399.3 5413.8 5416.6 5423.8 5271.1 5279.5 5339.8 5423.3 5502.0 5379.3 5397.3 5402.0 5411.4 5415.0 5384.6 5393.9 5397.9 5402.1 5415.6 5251.4 5331.1 5354.4 5357.6 5363.2 5935.6 5958.5 5961.9 5966.1 5968.0 6327.6 6361.9 6367.0 6369.6 6372.2 5618.9 6105.9 6322.9 6357.8 6404.1 5707.8 6125.1 6131.6 6133.6 6134.3 6267.6 6623.5 6631.3 6648.9 6654.8 6262.1 6271.2 6284.7 6342.7 6373.3 6382.2 6428.4 6439.2 6442.6 6472.9 6346.6 6354.9 6355.7 6356.0 6366.1 6158.8 6174.5 6266.0 6286.5 6361.3 4302.0 5145.8 5161.5 5189.2 5200.2 4558.2 4562.7 4564.3 4588.9 4612.8 5772.4 5837.2 6007.1 6069.5 6070.5 6324.7 6348.9 6367.4 6375.7 6379.5 6030.9 6057.2 6089.8 6098.8 6127.9 5670.5 5690.1 5691.5 5696.4 5698.1 6265.1 6304.5 6529.8 6548.5 6605.2 6387.6 6421.9 6430.8 6437.7 6438.2 6582.1 6658.5 6658.7 6663.3 6667.4 6275.0 6319.0 6333.0 6338.7 6352.2 6431.3 6438.8 6455.8 6462.8 6472.8 6271.8 6301.5 6337.4 6361.2 6368.8 6069.0 6136.4 6136.8 6234.9 6350.0 6163.4 6230.3 6242.8 6243.8 6244.6 5244.5 6331.2 6334.4 6339.9 6348.7 6001.2 6046.7 6077.6 6136.0 6140.2 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:56057.39 Median:33375.25 stddev:112362.95 (336.7%) stddev-sans-first:112601.33 22:49:07 INFO - PROCESS | 1641 | Values: 713.7 755.8 790.0 883.6 896.3 27700.3 28099.3 39791.2 40162.4 42321.8 41218.6 41402.8 41438.2 41524.0 41632.8 9191.4 9342.7 9349.3 9349.4 9367.5 7207.5 7374.9 10885.5 11080.6 11198.2 11075.2 11078.3 11094.3 11096.6 11120.8 10999.4 11024.3 11051.8 11055.6 11062.1 7238.8 7474.0 10637.9 10846.9 10890.3 38505.8 38821.8 38863.8 38872.0 38876.6 3858.4 3877.2 3910.8 3928.8 3965.3 26871.7 27983.7 28664.7 28916.7 29126.1 2382.9 2902.1 2946.8 2951.2 2951.7 303202.0 354173.0 354354.0 354383.2 355103.0 53169.0 53209.5 53248.7 53325.2 53466.7 45235.0 55398.9 55533.7 55542.4 55564.0 14480.3 14897.6 14900.6 14907.0 14943.4 12873.4 12888.1 12898.9 12909.5 12934.9 10324.3 10727.8 10802.1 11874.8 12037.3 5464.3 5516.5 5521.1 5523.0 5529.6 804.7 877.2 946.9 966.3 1021.5 28306.9 28666.4 28974.9 29036.6 29760.4 1046.8 1051.6 1078.4 1083.9 1087.6 14940.4 15051.0 15132.8 15245.5 15302.8 468821.0 469518.0 470770.0 472355.0 472504.0 56702.0 56845.6 56856.7 56904.0 56935.7 1418.7 1474.3 1474.3 1478.2 1480.3 26766.6 28230.9 28849.6 28898.9 28982.0 37321.6 45569.8 45646.0 45708.1 45719.0 443576.8 444659.0 444908.0 444989.0 445009.0 33190.0 33560.5 33956.8 34073.5 34112.3 33782.2 43499.6 44098.8 44512.7 46045.5 29798.1 29847.6 30256.6 30901.1 31105.8 29932.9 30053.5 30652.8 30889.4 30951.7 35498.1 35637.6 36427.1 36807.7 36922.4 30192.5 30283.7 30479.7 31330.4 31506.7 23277.3 23679.3 24593.9 25113.2 25139.0 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:40902.04 Median:20851.24 stddev:115885.43 (555.8%) stddev-sans-first:116170.63 22:49:07 INFO - PROCESS | 1641 | Values: 407.8 414.7 422.6 431.1 446.3 18446.4 19423.8 20768.6 20933.9 20949.0 17456.5 21620.0 22036.3 22088.3 22246.8 6343.7 6805.8 6815.6 6819.6 6822.4 6495.4 6637.9 6691.6 6718.8 6719.2 6390.6 6715.2 6726.7 6730.4 6734.2 6636.4 6681.8 6730.4 6739.0 6749.2 6232.3 6386.6 6388.2 6480.0 6571.3 26111.9 26192.0 26221.3 26296.9 26554.2 2866.1 2893.6 2894.0 3029.7 3041.6 15980.8 16004.1 16031.4 16241.6 17314.9 2117.6 2135.1 2151.1 2216.9 2224.3 108865.0 125014.4 127933.0 128934.4 129755.4 20451.9 20477.8 20489.0 20689.9 20707.5 33508.5 33859.5 33869.7 34014.5 34051.8 8426.5 10368.5 10563.9 10571.4 10806.4 7695.3 8080.2 8554.5 8591.4 8785.0 9749.8 9937.0 9941.4 9948.8 10064.1 4287.0 4330.8 4347.8 4368.3 4373.3 1020.7 1063.6 1067.9 1080.0 1119.2 19833.9 20402.9 20481.7 21290.7 21513.6 1071.2 1082.4 1101.3 1127.4 1130.3 10699.2 10950.9 10991.3 11020.7 11079.4 421165.0 608872.0 757307.2 795498.0 795777.0 167413.0 169036.0 170996.0 172477.7 180210.0 1248.3 1250.4 1304.9 1305.3 1311.0 18800.0 19114.3 19466.6 19537.0 19573.1 63449.0 63553.0 64504.0 64577.7 65456.7 90363.5 90609.0 92234.5 93775.6 93955.0 10283.5 10796.2 11346.9 11541.6 11762.9 8001.3 12135.4 12595.2 13025.2 13180.9 11354.2 11431.0 11557.7 11620.7 11675.5 10587.2 10668.7 10914.3 10995.9 11053.3 11428.3 11682.8 11842.7 11912.5 11988.9 11136.6 11429.0 11731.4 11908.6 12003.2 11169.1 11332.4 11352.4 11417.2 11448.9 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6613.93 Median:2829.40 stddev:4165.86 (147.2%) stddev-sans-first:4158.74 22:49:07 INFO - PROCESS | 1641 | Values: 1346.7 1451.4 1554.4 1584.9 1584.9 9197.0 9651.5 9662.8 9754.8 9757.8 9562.6 10250.9 10580.9 10623.4 10773.5 2350.7 2390.0 2395.6 2398.5 2400.2 1971.8 2245.3 2381.9 2427.9 2433.7 2009.9 2115.5 2115.7 2116.1 2117.1 2099.1 2123.9 2338.8 2420.1 2427.2 2420.5 2422.7 2424.8 2426.3 2427.4 11335.3 11356.7 11361.6 11365.8 11390.7 4881.1 5096.5 5107.2 5132.9 5137.6 3225.1 3539.8 3563.7 3669.8 3722.6 1214.3 1227.4 1237.0 1250.7 1806.5 15428.3 15524.3 15534.2 15565.8 15574.1 4691.1 4706.0 4708.2 4735.6 4781.8 9738.9 11470.5 11497.9 11498.0 11498.3 5702.8 5709.7 5713.1 5719.4 5725.8 4571.9 5049.1 5172.9 5252.5 5359.5 3799.0 3881.2 3882.3 3923.6 3938.2 1016.9 1028.6 1045.4 1046.4 1054.5 696.3 780.5 812.2 821.8 829.3 6466.6 6761.9 6836.1 7016.8 7061.4 974.7 1005.8 1008.8 1010.4 1022.0 4114.8 4117.7 4118.6 4175.4 4257.1 13219.4 13781.8 13784.2 13786.2 13806.8 12401.7 12637.1 12726.1 13674.2 13729.5 9869.8 10152.7 10156.1 10179.3 10193.2 6276.1 6545.8 6636.9 6647.0 6765.5 11953.9 12045.9 12195.2 12207.5 12394.2 12130.7 12509.0 12529.6 12536.6 12573.7 9033.0 9381.9 9385.5 9391.0 9393.4 10140.5 10209.8 10354.3 10359.3 10377.1 8539.2 8676.4 8681.5 8737.6 8881.7 7949.3 8445.7 8531.3 8636.0 8748.5 7225.9 7363.7 7437.3 7470.0 7470.0 5111.4 5829.9 5922.9 6094.1 6094.2 5896.4 5926.6 5989.3 5989.3 5993.1 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4473.07 Median:4859.29 stddev:1253.42 (25.8%) stddev-sans-first:1218.09 22:49:07 INFO - PROCESS | 1641 | Values: 347.5 371.3 375.8 382.1 387.3 4373.5 4375.3 4996.5 5038.4 5108.3 4228.9 4294.2 4309.5 4341.0 4935.5 3729.6 4033.9 4239.6 4263.3 4276.1 3304.9 3899.3 3910.6 4054.6 4205.7 3877.9 4183.0 4199.4 4206.1 4261.4 3764.7 4204.7 4221.3 4224.5 4254.7 3470.6 3757.8 3825.4 3885.2 3963.7 4151.7 4365.7 4421.3 4864.5 4868.9 4136.3 4409.6 4677.5 4804.3 4918.3 4309.4 5243.7 5379.8 5437.7 5440.8 3918.5 4133.9 4366.0 4375.6 4376.5 4811.7 5459.3 5472.9 5753.4 5798.0 3966.3 4001.0 4193.0 4201.8 4209.4 4045.8 4304.8 4473.9 4481.3 4508.6 4239.6 4882.9 4894.9 4908.8 4909.4 4824.0 5034.8 5302.6 5363.5 5449.3 1504.8 1519.6 1525.8 1532.4 1536.9 1003.6 1096.2 1099.3 1149.2 1161.5 4956.8 4958.6 4968.8 4980.8 5042.1 5041.0 5571.9 5581.6 5610.8 5612.9 4811.0 4896.4 4931.0 5013.0 5053.7 2584.7 2641.6 2713.0 2857.0 2897.0 4899.4 5163.1 5784.7 5794.4 5842.5 4906.7 5352.7 5461.3 5674.7 5852.4 5249.7 5512.0 5765.0 5813.5 5830.6 4876.9 5235.8 5551.1 5562.3 5586.6 5061.4 5521.4 5538.7 5562.9 5735.7 4675.9 5084.2 5088.4 5329.5 5813.4 4153.5 4260.1 4351.5 4382.0 4389.4 5287.5 5475.4 5659.8 5696.7 5709.8 4623.6 4750.7 4918.5 5143.5 5351.8 4797.5 4854.1 5410.3 5422.0 5428.5 4622.7 5255.0 5256.9 5304.9 5447.2 4826.0 5005.7 5344.2 5362.1 5448.5 4788.4 5238.1 5281.3 5316.5 5435.5 22:49:07 INFO - PROCESS | 1641 | 22:49:07 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19711.64 Median:3635.00 stddev:28199.44 (775.8%) stddev-sans-first:28242.47 22:49:07 INFO - PROCESS | 1641 | Values: 714.1 731.2 772.4 817.5 825.7 15131.2 15851.2 15866.7 15889.8 15932.7 14520.3 14530.0 14549.3 14621.8 14679.2 860.5 914.0 924.4 932.7 936.7 948.0 964.4 966.1 966.5 967.9 1014.8 1017.4 1018.0 1018.2 1023.2 958.9 1009.9 1012.0 1013.3 1014.0 754.2 755.2 756.3 756.4 757.0 15716.4 15964.5 16051.8 16382.9 16634.8 12744.0 14086.4 14220.4 14389.9 14502.6 9882.8 11729.2 11937.0 12062.6 12100.9 3533.6 3612.4 3660.9 3781.0 3794.7 112229.0 113788.0 115123.4 115448.0 117973.0 10254.4 10752.3 11036.1 11116.2 11237.9 18496.7 21698.6 22039.6 22193.2 22235.1 7360.4 7360.6 7437.7 7745.4 7825.0 4265.7 4273.1 4358.0 4371.9 4466.6 3317.8 3417.9 3430.2 3438.6 3442.5 516.2 522.1 522.7 539.0 541.7 490.6 527.8 530.8 539.2 568.0 9099.8 9135.9 9590.9 9745.2 9904.1 633.1 650.7 654.0 675.9 683.4 3657.6 4655.0 4664.1 4671.9 4672.1 97204.0 97211.0 98377.5 98450.0 100332.5 56772.0 71139.0 73405.0 79820.6 79912.0 72964.0 73671.0 75824.6 75860.6 77085.1 10827.8 11066.8 11090.7 11152.9 11315.4 44632.9 44672.0 44896.6 45236.0 46386.5 45970.5 57917.4 58780.7 59004.4 69853.0 12537.4 13043.8 13257.8 13263.6 13524.3 13419.4 14897.9 15142.9 15433.0 15468.8 13427.0 13671.2 13742.9 13839.9 13977.0 13945.2 14073.7 14421.5 14430.5 14762.3 13178.5 13855.4 13896.4 14364.4 14366.6 8240.4 8704.7 9140.2 9186.3 9195.8 10690.7 11470.9 11485.9 11586.9 11611.7 22:49:07 INFO - PROCESS | 1641 | -------- Summary: end -------- 22:49:07 INFO - PROCESS | 1641 | 22:49:08 INFO - TEST-INFO | 1641: exit 0 22:49:08 INFO - TEST-OK | dromaeo_css | took 1120190ms 22:49:08 INFO - TEST-START | kraken 22:49:08 INFO - operating with platform_type : mac_ 22:49:08 INFO - Initialising browser for kraken test... 22:49:08 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpp55RQ_/profile http://localhost:49244/getInfo.html) 22:49:10 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 22:49:10 INFO - PROCESS | 1651 | colorDepth:24 22:49:10 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 22:49:10 INFO - PROCESS | 1651 | __metrics 22:49:10 INFO - PROCESS | 1651 | 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" 22:49:10 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:49:11 INFO - TEST-INFO | 1651: exit 0 22:49:11 INFO - Browser initialized. 22:49:11 INFO - Running cycle 1/1 for kraken test... 22:49:11 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpp55RQ_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:23 INFO - PROCESS | 1652 | RSS: Main: 210534400 22:49:23 INFO - PROCESS | 1652 | 22:49:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 22:49:27 INFO - PROCESS | 1652 | RSS: Main: 264323072 22:49:27 INFO - PROCESS | 1652 | 22:49:30 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 22:49:30 INFO - PROCESS | 1652 | RSS: Main: 277909504 22:49:30 INFO - PROCESS | 1652 | 22:49:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 22:49:34 INFO - PROCESS | 1652 | RSS: Main: 375443456 22:49:34 INFO - PROCESS | 1652 | 22:49:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 22:49:37 INFO - PROCESS | 1652 | RSS: Main: 277889024 22:49:37 INFO - PROCESS | 1652 | 22:49:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:49:39 INFO - PROCESS | 1652 | RSS: Main: 279715840 22:49:39 INFO - PROCESS | 1652 | 22:49:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 22:49:43 INFO - PROCESS | 1652 | RSS: Main: 320438272 22:49:43 INFO - PROCESS | 1652 | 22:49:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 22:49:47 INFO - PROCESS | 1652 | RSS: Main: 319799296 22:49:47 INFO - PROCESS | 1652 | 22:49:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 22:49:50 INFO - PROCESS | 1652 | RSS: Main: 319090688 22:49:50 INFO - PROCESS | 1652 | 22:49:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:49:53 INFO - PROCESS | 1652 | RSS: Main: 296230912 22:49:53 INFO - PROCESS | 1652 | 22:49:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:49:55 INFO - PROCESS | 1652 | RSS: Main: 299388928 22:49:55 INFO - PROCESS | 1652 | 22:49:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:49:58 INFO - PROCESS | 1652 | RSS: Main: 306397184 22:49:58 INFO - PROCESS | 1652 | 22:50:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:50:02 INFO - PROCESS | 1652 | RSS: Main: 320401408 22:50:02 INFO - PROCESS | 1652 | 22:50:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:50:05 INFO - PROCESS | 1652 | RSS: Main: 314056704 22:50:05 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 22:50:07 INFO - PROCESS | 1652 | RSS: Main: 358797312 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | __start_tp_report 22:50:07 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 22:50:07 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 22:50:07 INFO - PROCESS | 1652 | |i|pagename|runs| 22:50:07 INFO - PROCESS | 1652 | |0;ai-astar;106;119;117;115;118;117;116;117;116;117 22:50:07 INFO - PROCESS | 1652 | |1;audio-beat-detection;147;134;132;140;110;109;104;106;128;128 22:50:07 INFO - PROCESS | 1652 | |2;audio-dft;185;187;186;185;184;185;183;186;186;186 22:50:07 INFO - PROCESS | 1652 | |3;audio-fft;81;62;61;93;62;62;61;62;76;59 22:50:07 INFO - PROCESS | 1652 | |4;audio-oscillator;92;68;87;69;69;68;83;68;68;84 22:50:07 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;88;88;88;88;88;88;90;134;88 22:50:07 INFO - PROCESS | 1652 | |6;imaging-darkroom;96;96;96;99;95;95;95;99;95;96 22:50:07 INFO - PROCESS | 1652 | |7;imaging-desaturate;87;84;86;87;87;87;87;99;139;95 22:50:07 INFO - PROCESS | 1652 | |8;json-parse-financial;63;62;61;61;64;64;63;66;64;64 22:50:07 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;57;60;58;64;61;59;58;58;58;59 22:50:07 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;87;85;80;83;84;85;81;86;87;74 22:50:07 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;163;149;147;138;138;134;136;146;202;163 22:50:07 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;170;165;162;164;164;167;165;166;163;163 22:50:07 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;64;66;64;66;64;64;64;64;65 22:50:07 INFO - PROCESS | 1652 | __end_tp_report 22:50:07 INFO - PROCESS | 1652 | __start_cc_report 22:50:07 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2158 22:50:07 INFO - PROCESS | 1652 | __end_cc_report 22:50:07 INFO - PROCESS | 1652 | __startTimestamp1492494607444__endTimestamp 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | ------- Summary: start ------- 22:50:07 INFO - PROCESS | 1652 | Number of tests: 14 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:115.80 Median:117.00 stddev:3.61 (3.1%) stddev-sans-first:1.17 22:50:07 INFO - PROCESS | 1652 | Values: 106.0 119.0 117.0 115.0 118.0 117.0 116.0 117.0 116.0 117.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:123.80 Median:130.00 stddev:15.37 (11.8%) stddev-sans-first:13.82 22:50:07 INFO - PROCESS | 1652 | Values: 147.0 134.0 132.0 140.0 110.0 109.0 104.0 106.0 128.0 128.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.00 stddev:1.16 (0.6%) stddev-sans-first:1.22 22:50:07 INFO - PROCESS | 1652 | Values: 185.0 187.0 186.0 185.0 184.0 185.0 183.0 186.0 186.0 186.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:67.90 Median:62.00 stddev:11.45 (18.5%) stddev-sans-first:11.13 22:50:07 INFO - PROCESS | 1652 | Values: 81.0 62.0 61.0 93.0 62.0 62.0 61.0 62.0 76.0 59.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.00 stddev:9.67 (12.7%) stddev-sans-first:8.24 22:50:07 INFO - PROCESS | 1652 | Values: 92.0 68.0 87.0 69.0 69.0 68.0 83.0 68.0 68.0 84.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:88.00 stddev:14.46 (16.4%) stddev-sans-first:15.26 22:50:07 INFO - PROCESS | 1652 | Values: 89.0 88.0 88.0 88.0 88.0 88.0 88.0 90.0 134.0 88.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:96.00 stddev:1.55 (1.6%) stddev-sans-first:1.64 22:50:07 INFO - PROCESS | 1652 | Values: 96.0 96.0 96.0 99.0 95.0 95.0 95.0 99.0 95.0 96.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:87.00 stddev:16.53 (19.0%) stddev-sans-first:17.35 22:50:07 INFO - PROCESS | 1652 | Values: 87.0 84.0 86.0 87.0 87.0 87.0 87.0 99.0 139.0 95.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:64.00 stddev:1.55 (2.4%) stddev-sans-first:1.64 22:50:07 INFO - PROCESS | 1652 | Values: 63.0 62.0 61.0 61.0 64.0 64.0 63.0 66.0 64.0 64.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:2.04 (3.5%) stddev-sans-first:2.01 22:50:07 INFO - PROCESS | 1652 | Values: 57.0 60.0 58.0 64.0 61.0 59.0 58.0 58.0 58.0 59.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:85.00 stddev:3.99 (4.7%) stddev-sans-first:3.99 22:50:07 INFO - PROCESS | 1652 | Values: 87.0 85.0 80.0 83.0 84.0 85.0 81.0 86.0 87.0 74.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:148.00 stddev:20.50 (13.9%) stddev-sans-first:21.32 22:50:07 INFO - PROCESS | 1652 | Values: 163.0 149.0 147.0 138.0 138.0 134.0 136.0 146.0 202.0 163.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:165.00 stddev:2.33 (1.4%) stddev-sans-first:1.58 22:50:07 INFO - PROCESS | 1652 | Values: 170.0 165.0 162.0 164.0 164.0 167.0 165.0 166.0 163.0 163.0 22:50:07 INFO - PROCESS | 1652 | 22:50:07 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.50 stddev:0.95 (1.5%) stddev-sans-first:0.88 22:50:07 INFO - PROCESS | 1652 | Values: 66.0 64.0 66.0 64.0 66.0 64.0 64.0 64.0 64.0 65.0 22:50:07 INFO - PROCESS | 1652 | -------- Summary: end -------- 22:50:07 INFO - PROCESS | 1652 | 22:50:08 INFO - TEST-INFO | 1652: exit 0 22:50:08 INFO - TEST-OK | kraken | took 59884ms 22:50:08 INFO - SUITE-END | took 1180s 22:50:08 INFO - Completed test suite (00:19:40) 22:50:08 INFO - javascript benchmark 22:50:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7626.010625963011, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3427.7600567960253, 3439.1058324835544, 3446.0, 3451.0682115828727, 3484.9651503484956, 6013.398660133985, 6033.306169790475, 6044.727987240573, 6084.665343406116, 6088.756449742011, 5992.460678538931, 6058.727357268923, 6067.908981365283, 6081.0878368244785, 6081.118237855509, 5366.356037275523, 5399.325084364455, 5413.837584872441, 5416.620836541444, 5423.83728488144, 5271.103912334893, 5279.472052794709, 5339.813106541272, 5423.267858839065, 5501.972490137544, 5379.273798037254, 5397.298351214336, 5402.0, 5411.377691565462, 5414.972925135369, 5384.6499977501335, 5393.946060539422, 5397.892042159174, 5402.135658294694, 5415.620906536544, 5251.396089449724, 5331.1470164773455, 5354.357477102733, 5357.598180136505, 5363.168708850135, 5935.55483338747, 5958.523318134538, 5961.940380596182, 5966.134910438005, 5968.0, 6327.588706734094, 6361.936380636181, 6366.981282994699, 6369.585976911486, 6372.203474565679, 5618.932402843447, 6105.884339886945, 6322.9051564226575, 6357.777477788255, 6404.071409645622, 5707.809293663565, 6125.050617154348, 6131.632102073845, 6133.622130947118, 6134.294556126013, 6267.62394256346, 6623.503237257177, 6631.27056023837, 6648.900266496006, 6654.833629159213, 6262.09199666055, 6271.216097987752, 6284.717187726564, 6342.651154186472, 6373.330800265998, 6382.170317858649, 6428.421442070143, 6439.195100612424, 6442.581232219891, 6472.870542589122, 6346.619202847844, 6354.904676429857, 6355.650439225795, 6355.968220158869, 6366.076918846741, 6158.753649854045, 6174.475169610543, 6266.0, 6286.528510361742, 6361.268454127742, 4301.956980430155, 5145.845624631267, 5161.483851614808, 5189.169732842727, 5200.2199670049795, 4558.1795276849825, 4562.749048802348, 4564.3153526970555, 4588.898664320539, 4612.838550650711, 5772.422757724194, 5837.211976383134, 6007.128966299862, 6069.453749162508, 6070.453659170696, 6324.6521441321165, 6348.936510634927, 6367.363263673596, 6375.74497020114, 6379.521535884827, 6030.879382412327, 6057.242844644419, 6089.786857460056, 6098.756049758049, 6127.908081378783, 5670.489655930904, 5690.089585666274, 5691.516221121168, 5696.3733989261145, 5698.145278208219, 6265.122882796503, 6304.5271604628915, 6529.804105876831, 6548.541602087808, 6605.207375115017, 6387.55287129915, 6421.903671444838, 6430.807075787734, 6437.710303036468, 6438.163038804925, 6582.078509008838, 6658.467322614276, 6658.7336506539195, 6663.26704062543, 6667.399934005769, 6275.0, 6319.0, 6332.986722124622, 6338.683065846597, 6352.174217351715, 6431.29255781873, 6438.8390290243115, 6455.838604034937, 6462.838429039311, 6472.805815825533, 6271.78048768291, 6301.527385446018, 6337.429631333202, 6361.236651601653, 6368.840778980377, 6068.969655151696, 6136.417040381157, 6136.825445958321, 6234.937650623436, 6349.952257877399, 6163.3528479509005, 6230.314665386713, 6242.812715618538, 6243.750249989949, 6244.594101383395, 5244.501772331622, 6331.176946997045, 6334.429901308904, 6339.936600633934, 6348.746050157942, 6001.159837622649, 6046.667433291212, 6077.574569780073, 6136.0, 6140.197513349842], "value": 5890.343873663648, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.7430525010996, 755.7883792538088, 789.9605019749011, 883.5935469683945, 896.3456676626058, 27700.307492312677, 28099.29751756207, 39791.2041759165, 40162.39756403649, 42321.78839105808, 41218.55735049274, 41402.757917262534, 41438.17123657533, 41524.0, 41632.79183604078, 9191.356605037634, 9342.673006444777, 9349.252059835197, 9349.39228950116, 9367.484788336638, 7207.49547531673, 7374.852502949964, 10885.510152043176, 11080.612178573752, 11198.160137989642, 11075.16936229786, 11078.335299882034, 11094.334339939589, 11096.55613775448, 11120.777584448306, 10999.395033273167, 11024.283421577651, 11051.778964420706, 11055.613053543088, 11062.115030797517, 7238.782836514913, 7474.028376311099, 10637.9468102659, 10846.891531084746, 10890.346579205274, 38505.80747096275, 38821.805890970645, 38863.8340849775, 38872.0, 38876.63931762375, 3858.4405261237243, 3877.1664092220026, 3910.843566257346, 3928.764274143532, 3965.306071437487, 26871.731282687117, 27983.72016279852, 28664.713352866413, 28916.71083289161, 29126.126216213546, 2382.9395918816135, 2902.085842959461, 2946.8379239141836, 2951.1736713720193, 2951.74910132639, 303202.0, 354173.0, 354354.0, 354383.22808386054, 355103.0, 53169.0, 53209.467905321224, 53248.73375633097, 53325.2001219982, 53466.7326663372, 45235.0, 55398.89202216014, 55533.72233138808, 55542.44457555372, 55564.0, 14480.348384322731, 14897.553073407815, 14900.552983410515, 14907.031042982171, 14943.40226390954, 12873.420696068699, 12888.097833151589, 12898.871011289955, 12909.54816581415, 12934.935325323313, 10324.328918620266, 10727.83908241377, 10802.135829133807, 11874.821877671842, 12037.277763334228, 5464.26232458621, 5516.538117398893, 5521.1442226454965, 5522.972385138049, 5529.640573362719, 804.6861723927556, 877.2280393253895, 946.9005754395689, 966.3235734985398, 1021.4688362051692, 28306.85846570754, 28666.42667146687, 28974.855125724658, 29036.564451533242, 29760.404791904042, 1046.7540128069897, 1051.5793682526898, 1078.412265315405, 1083.8970297821695, 1087.5541028178409, 14940.402383904739, 15051.0, 15132.761427739099, 15245.542633721006, 15302.846971530365, 468821.0, 469518.0, 470770.0, 472355.0, 472504.0, 56702.0, 56845.57886052799, 56856.71571642198, 56904.0, 56935.71532142396, 1418.7446259673152, 1474.3266392644425, 1474.3439169569438, 1478.209158100411, 1480.25247250138, 26766.59850102289, 28230.85884570564, 28849.567256491588, 28898.85550572234, 28982.0, 37321.626783731816, 45569.772151139034, 45646.0, 45708.08583828371, 45719.0, 443576.7821160873, 444659.0, 444908.0, 444989.0, 445009.0, 33190.0, 33560.49659255064, 33956.830215848764, 34073.48889766705, 34112.31775364429, 33782.15544611404, 43499.56500434955, 44098.77950610226, 44512.66462006145, 46045.53954460412, 29798.10605681833, 29847.552286715283, 30256.565887731762, 30901.072967811, 31105.844470778407, 29932.85033574905, 30053.549196762502, 30652.84673576618, 30889.382212355176, 30951.690483095783, 35498.11254718652, 35637.64362356447, 36427.08932276714, 36807.711730089315, 36922.44616330703, 30192.54711179378, 30283.69716302897, 30479.695203047686, 31330.373392531565, 31506.684933151293, 23277.3016809496, 23679.28962131139, 24593.87703061473, 25113.24660260195, 25139.0], "value": 18675.12665579488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.82871194098476, 414.6869113819066, 422.56686895931665, 431.0925501818672, 446.3171347837807, 18446.40295142448, 19423.805761942378, 20768.584628307424, 20933.895330523344, 20949.0, 17456.476305710818, 21620.0, 22036.33890983273, 22088.33734987949, 22246.777532224674, 6343.714532846033, 6805.761798337059, 6815.625140617289, 6819.556728812611, 6822.351876571718, 6495.415412612864, 6637.900431493532, 6691.6319602421845, 6718.764843230488, 6719.160104986877, 6390.584612000228, 6715.227748808877, 6726.69729862155, 6730.4279136273235, 6734.225564060122, 6636.369544893228, 6681.7995460136035, 6730.427913627347, 6738.966305168443, 6749.2238392584495, 6232.283287421959, 6386.552941294111, 6388.24562804442, 6479.967600162016, 6571.2771595124495, 26111.869440652674, 26191.9523219071, 26221.34446638849, 26296.86851565749, 26554.20337389862, 2866.125831621355, 2893.6093627360247, 2893.956590651142, 3029.712177343149, 3041.558973948765, 15980.840191598052, 16004.119773412458, 16031.35874565016, 16241.593960150973, 17314.91342543279, 2117.5976564452703, 2135.1139277199945, 2151.08578853988, 2216.8780717060554, 2224.2659922225625, 108865.0, 125014.37492812569, 127933.0, 128934.3553282237, 129755.35122324423, 20451.897740511202, 20477.79522204759, 20489.025338410294, 20689.89655051715, 20707.482312941996, 33508.49737253943, 33859.492107618404, 33869.66130338714, 34014.48978265328, 34051.82974085163, 8426.452280601741, 10368.533415996148, 10563.94718026405, 10571.365718056788, 10806.406462448957, 7695.345895598824, 8080.232377924088, 8554.529500877512, 8591.44155629882, 8785.0, 9749.80758273949, 9936.950315248521, 9941.403515789076, 9948.751281218027, 10064.144547713526, 4286.957130428718, 4330.848420305274, 4347.804348804311, 4368.279233926367, 4373.300271956472, 1020.6935123042517, 1063.5905176506947, 1067.887871773468, 1079.9902091544275, 1119.2445099557765, 19833.900830496044, 20402.89798550998, 20481.692774608393, 21290.680639790415, 21513.569728605653, 1071.223363061774, 1082.377632861108, 1101.2786624760795, 1127.4193790198055, 1130.3161587239765, 10699.197560183018, 10950.945245273882, 10991.340519568852, 11020.724481888017, 11079.390633515073, 421165.0, 608872.0, 757307.2134639291, 795498.0, 795777.0, 167413.0, 169036.0, 170996.0, 172477.68805779706, 180210.0, 1248.2510493703705, 1250.412306216077, 1304.9347532623522, 1305.2560040776857, 1310.9737805243842, 18800.0, 19114.33099841499, 19466.61066778628, 19537.0, 19573.119209635886, 63449.0, 63553.0, 64504.0, 64577.67711161602, 65456.67271663611, 90363.54818225867, 90609.0, 92234.53882730543, 93775.59336610093, 93955.0, 10283.537240824331, 10796.244262901522, 11346.94326528362, 11541.596044138414, 11762.882371176522, 8001.319887809602, 12135.393230338625, 12595.24428534291, 13025.153365031243, 13180.934095329461, 11354.205205635526, 11431.0, 11557.7110572233, 11620.6513804586, 11675.474603643026, 10587.20595955291, 10668.733281668021, 10914.290571112851, 10995.945020274847, 11053.336799791716, 11428.25716328436, 11682.76634467323, 11842.703932401759, 11912.463939122586, 11988.940055299669, 11136.554537818396, 11429.0, 11731.41342932867, 11908.642740717793, 12003.159778815749, 11169.106471482424, 11332.433378331216, 11352.375619341015, 11417.200795944203, 11448.855114488484], "value": 11635.105955139892, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.6566025663453, 1451.4194322271092, 1554.3704799556176, 1584.8573628373451, 1584.8732101431888, 9197.0, 9651.4691691957, 9662.75843103922, 9754.804903901917, 9757.7560560986, 9562.617495300197, 10250.897491025087, 10580.947095264513, 10623.36259824411, 10773.515191816368, 2350.7296660883962, 2389.95220095598, 2395.592749232626, 2398.508305797316, 2400.2079313826393, 1971.7633883933947, 2245.3264020793695, 2381.880905954704, 2427.854328740272, 2433.707955045397, 2009.939701808948, 2115.4605575578285, 2115.7249557557498, 2116.0795054151495, 2117.100232401231, 2099.149844313048, 2123.872567645946, 2338.847974881627, 2420.1408499982604, 2427.235420842429, 2420.45539753554, 2422.6796395964243, 2424.842385244953, 2426.3085020768995, 2427.4416884116754, 11335.319880807177, 11356.659300220925, 11361.602343918006, 11365.772684546346, 11390.71523211918, 4881.072596206709, 5096.464871188509, 5107.157319042353, 5132.948670513284, 5137.588992880598, 3225.0969728476066, 3539.7876127432437, 3563.6614521620404, 3669.7614655047337, 3722.5532936047575, 1214.3017764785284, 1227.4169769359482, 1236.9567065152767, 1250.7060840702427, 1806.530302121458, 15428.305726242346, 15524.301406436625, 15534.223288835627, 15565.766513502307, 15574.065556066558, 4691.132140554008, 4705.976470117696, 4708.152532544175, 4735.621150307967, 4781.832635857728, 9738.902610973799, 11470.48382822775, 11497.942510287396, 11498.0, 11498.25261358009, 5702.7718891243885, 5709.74306156224, 5713.05734553794, 5719.370869204384, 5725.799597014085, 4571.862844114682, 5049.141645920203, 5172.8965420691375, 5252.501012403815, 5359.490848369399, 3798.9810050950123, 3881.204353107606, 3882.320593896057, 3923.5684074751753, 3938.232044751246, 1016.9373005209574, 1028.5628607841638, 1045.4354648489882, 1046.3617193511952, 1054.4780333734698, 696.3002182806247, 780.5043797188713, 812.2364976921792, 821.8356328734277, 829.266099501933, 6466.579672321284, 6761.93238067613, 6836.111305530249, 7016.824579385454, 7061.364477197077, 974.7124598243536, 1005.818952588541, 1008.7793769538794, 1010.4190090697878, 1022.0494939705997, 4114.794260286973, 4117.670586353084, 4118.588141185917, 4175.436316097349, 4257.084726783756, 13219.405126769318, 13781.793273100911, 13784.241866697228, 13786.173834363932, 13806.792898106536, 12401.689957751129, 12637.115401921777, 12726.109172357847, 13674.179549227078, 13729.519466818812, 9869.75325616851, 10152.695419137437, 10156.085952264331, 10179.338343007828, 10193.184545236363, 6276.058591211355, 6545.770898018546, 6636.867262654817, 6647.0, 6765.5264131512295, 11953.94023029914, 12045.939770301093, 12195.207311524722, 12207.511699531919, 12394.194377365442, 12130.696732581757, 12509.0, 12529.624111276677, 12536.623901282976, 12573.74852502963, 9032.954835225783, 9381.906180938104, 9385.530723463937, 9390.95304523473, 9393.389429687322, 10140.49297535135, 10209.795804083727, 10354.326968747007, 10359.32664376813, 10377.11794497446, 8539.231469167804, 8676.392652514263, 8681.522516261666, 8737.606807693543, 8881.73354799357, 7949.284564389232, 8445.66217351299, 8531.274841638531, 8636.0, 8748.47509149453, 7225.9277407227355, 7363.705451781869, 7437.256274372519, 7470.0, 7470.0, 5111.437741848319, 5829.883402331845, 5922.851928701817, 6094.055421409688, 6094.177286066324, 5896.380880007709, 5926.644401335933, 5989.251343582052, 5989.2812862456785, 5993.0710739835395], "value": 5031.646348289426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.51695143750186, 371.33716316375273, 375.80645967326814, 382.069660376982, 387.2970558436436, 4373.4970478395, 4375.278079116942, 4996.4503904570465, 5038.370203724535, 5108.2848401223855, 4228.89427764306, 4294.227039132955, 4309.461317335333, 4340.978295108528, 4935.531124543171, 3729.627037296262, 4033.8705162554384, 4239.6396306313845, 4263.275243208646, 4276.075765905347, 3304.8678052877976, 3899.278633452806, 3910.6284902934176, 4054.5738850671137, 4205.747655140702, 3877.9224415511812, 4182.97908510457, 4199.370094485821, 4206.1377417629465, 4261.424707664472, 3764.73646844721, 4204.663626909855, 4221.261279276145, 4224.514180869178, 4254.680898932577, 3470.583529976394, 3757.849686012556, 3825.407061905409, 3885.203533275672, 3963.6829053675638, 4151.730137541081, 4365.672574556921, 4421.336799480072, 4864.513548645143, 4868.853934381974, 4136.255474014676, 4409.559044095565, 4677.532246775329, 4804.303376010494, 4918.262260660929, 4309.4397728295435, 5243.737813109329, 5379.767396530435, 5437.673739575638, 5440.830221502357, 3918.5297764268184, 4133.940883746036, 4366.039471316305, 4375.649948004152, 4376.518582955872, 4811.687240329367, 5459.2629994950175, 5472.890542189134, 5753.395893431171, 5798.0, 3966.345552983725, 4001.0019695578258, 4192.972721683207, 4201.75206264653, 4209.410682504452, 4045.8240281379562, 4304.784760762012, 4473.865784026484, 4481.327800829901, 4508.571685689854, 4239.554846741048, 4882.902341953141, 4894.902101957941, 4908.828191013297, 4909.435414927258, 4823.998040813093, 5034.84895453137, 5302.5683065572075, 5363.490468405495, 5449.302595215394, 1504.751715966875, 1519.6352875309847, 1525.8174914441174, 1532.3793863485307, 1536.9001014933995, 1003.6437064842022, 1096.1803406345427, 1099.312929419113, 1149.230015889356, 1161.5411912294596, 4956.801727930914, 4958.553730164303, 4968.826091086795, 4980.808444284576, 5042.092423363756, 5040.950225113754, 5571.9442805572235, 5581.609287349846, 5610.831675049755, 5612.85967850807, 4810.95189048112, 4896.36347274852, 4931.013797240566, 5013.022460620144, 5053.747312634354, 2584.7286034966432, 2641.564141916601, 2712.9826315131827, 2857.0428906316615, 2896.985515072411, 4899.447900272274, 5163.07064728345, 5784.681842498703, 5794.362620111784, 5842.474177324061, 4906.730129842822, 5352.678839269656, 5461.2627295315415, 5674.687892165971, 5852.356240813507, 5249.740062385, 5511.972440137773, 5764.962306784819, 5813.47114005409, 5830.591858569859, 4876.854673527615, 5235.764390602356, 5551.084071128219, 5562.276904002454, 5586.581006424616, 5061.443241243386, 5521.355905102613, 5538.723063846711, 5562.944370556243, 5735.71321433935, 4675.883102922318, 5084.161113416245, 5088.389393272831, 5329.493698098674, 5813.418658134153, 4153.460050193456, 4260.105377870682, 4351.521332653342, 4381.956180438155, 4389.38831395332, 5287.471252874681, 5475.35739278207, 5659.801906933238, 5696.718238396327, 5709.771609135588, 4623.606993405599, 4750.674527830602, 4918.532739389752, 5143.485651434827, 5351.785928562814, 4797.481322305678, 4854.063291708466, 5410.323709536308, 5421.969825733098, 5428.45715428454, 4622.6764126510825, 5254.97372513135, 5256.948610277883, 5304.867378315573, 5447.22191682237, 4826.0, 5005.674631148963, 5344.225087362389, 5362.115250983544, 5448.509634132947, 4788.4253889533475, 5238.1142788596835, 5281.260623512788, 5316.494932981361, 5435.483629055233], "value": 4109.068997634526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.1251966341235, 731.2322061835075, 772.3975299266571, 817.5421763812266, 825.7440193540003, 15131.243437828105, 15851.207439628015, 15866.682666346665, 15889.841101588981, 15932.681346373065, 14520.3465844037, 14530.0, 14549.345279462423, 14621.780673289883, 14679.192644404553, 860.496609483453, 913.9634414623424, 924.4360939826701, 932.6922115701836, 936.7002559181064, 948.042477098129, 964.3972517176765, 966.0677446264353, 966.5119114847028, 967.9274054445945, 1014.8426993815971, 1017.4200705597813, 1017.959281628734, 1018.221060888421, 1023.2019025160387, 958.9376690515094, 1009.8990100989915, 1011.9747006324827, 1013.2552573858209, 1013.9898601014006, 754.1817128415676, 755.1881727143343, 756.315534441326, 756.4440136499668, 757.0272200222743, 15716.449924252596, 15964.521064368088, 16051.759223611654, 16382.918085409616, 16634.833651663448, 12744.0, 14086.436542538286, 14220.360083796151, 14389.856101438956, 14502.56492305222, 9882.802343953153, 11729.178957472981, 11937.0, 12062.577809776703, 12100.939495302468, 3533.575970883485, 3612.3678356287805, 3660.853565857389, 3780.9621903780885, 3794.6964242860504, 112229.0, 113788.0, 115123.42438287838, 115448.0, 117973.0, 10254.384736915808, 10752.301100428447, 11036.11711063129, 11116.166287528467, 11237.887621123848, 18496.722549161772, 21698.56602867934, 22039.559208815735, 22193.22323718662, 22235.11059557632, 7360.374368178657, 7360.558366498028, 7437.665305061285, 7745.419093568005, 7825.0, 4265.722728022669, 4273.1240095780295, 4357.9782101089295, 4371.890702732457, 4466.553344665507, 3317.800931944091, 3417.863285468603, 3430.2281986552825, 3438.638942911008, 3442.5180474733556, 516.161237988269, 522.1384715219917, 522.6550476685369, 538.9649672771258, 541.6993568569403, 490.63202598051464, 527.7702952121548, 530.7691154347793, 539.2127493858932, 568.0456832521404, 9099.772505687279, 9135.862962055575, 9590.904090959, 9745.220382369393, 9904.108630223314, 633.1104797759156, 650.677914432359, 653.9967300163469, 675.864827034595, 683.4361651637419, 3657.634236576321, 4654.976725116353, 4664.090502352076, 4671.883202919954, 4672.112298663243, 97204.0, 97211.0278897216, 98377.50811245898, 98450.01549984551, 100332.49833750784, 56772.0, 71139.0, 73405.0, 79820.60089699514, 79912.0, 72964.0, 73671.0, 75824.62087689526, 75860.62069689616, 77085.0728731775, 10827.837582436112, 11066.778664426505, 11090.667279981613, 11152.944235278772, 11315.377654229096, 44632.884177895816, 44672.0, 44896.55103448924, 45236.0, 46386.53613463822, 45970.54029459663, 57917.42082579289, 58780.70609646924, 59004.40995589989, 69853.0, 12537.373131343214, 13043.804342934674, 13257.817485641292, 13263.602091937257, 13524.323783810967, 13419.396127174105, 14897.925510372379, 15142.924285378944, 15433.0, 15468.845311547191, 13427.0, 13671.17972921628, 13742.93128534351, 13839.861601383856, 13977.0, 13945.163290202214, 14073.718525629634, 14421.4952476667, 14430.494932677306, 14762.261886905826, 13178.538751144048, 13855.445782168601, 13896.374663140385, 14364.425422982964, 14366.640833979234, 8240.423170378015, 8704.738857834274, 9140.223081038186, 9186.31102667316, 9195.816083678423, 10690.679279621623, 11470.885291146982, 11485.942570287096, 11586.942065289619, 11611.6516504505], "value": 7432.130070725839, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1438.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 117.0, 115.0, 118.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 134.0, 132.0, 140.0, 110.0, 109.0, 104.0, 106.0, 128.0, 128.0], "value": 123.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 186.0, 185.0, 184.0, 185.0, 183.0, 186.0, 186.0, 186.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 61.0, 93.0, 62.0, 62.0, 61.0, 62.0, 76.0, 59.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 68.0, 87.0, 69.0, 69.0, 68.0, 83.0, 68.0, 68.0, 84.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 88.0, 88.0, 88.0, 88.0, 90.0, 134.0, 88.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 99.0, 95.0, 95.0, 95.0, 99.0, 95.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 87.0, 87.0, 87.0, 87.0, 99.0, 139.0, 95.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 61.0, 64.0, 64.0, 63.0, 66.0, 64.0, 64.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 58.0, 64.0, 61.0, 59.0, 58.0, 58.0, 58.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 80.0, 83.0, 84.0, 85.0, 81.0, 86.0, 87.0, 74.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 149.0, 147.0, 138.0, 138.0, 134.0, 136.0, 146.0, 202.0, 163.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 165.0, 162.0, 164.0, 164.0, 167.0, 165.0, 166.0, 163.0, 163.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 64.0, 66.0, 64.0, 64.0, 64.0, 64.0, 65.0], "value": 64.7, "unit": "score"}]}]} 22:50:08 INFO - Return code: 0 22:50:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:50:08 ERROR - # TBPL SUCCESS # 22:50:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:50:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:50:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:50:08 INFO - 2017-04-17 22:50:08.777 screenresolution[1656:9786] starting screenresolution argv=screenresolution get 22:50:08 INFO - 2017-04-17 22:50:08.780 screenresolution[1656:9786] Display 0: 1600x1200x32@60 22:50:08 INFO - 2017-04-17 22:50:08.786 screenresolution[1657:9789] starting screenresolution argv=screenresolution list 22:50:08 INFO - Available Modes on Display 0 22:50:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:50:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:50:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:50:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:50:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:50:09 INFO - Intel Iris: 22:50:09 INFO - Chipset Model: Intel Iris 22:50:09 INFO - Type: GPU 22:50:09 INFO - Bus: Built-In 22:50:09 INFO - VRAM (Dynamic, Max): 1536 MB 22:50:09 INFO - Vendor: Intel (0x8086) 22:50:09 INFO - Device ID: 0x0a2e 22:50:09 INFO - Revision ID: 0x0009 22:50:09 INFO - Displays: 22:50:09 INFO - Display: 22:50:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:50:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:50:09 INFO - Display Serial Number: 1600x1200 60 22:50:09 INFO - Main Display: Yes 22:50:09 INFO - Mirror: Off 22:50:09 INFO - Online: Yes 22:50:09 INFO - Rotation: Supported 22:50:09 INFO - Return code: 0 22:50:09 INFO - Running post-action listener: _resource_record_post_action 22:50:09 INFO - [mozharness: 2017-04-18 05:50:09.021797Z] Finished run-tests step (success) 22:50:09 INFO - Running post-run listener: _resource_record_post_run 22:50:09 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 12132352; Write bytes: 507759616; Read time: 125; Write time: 1738 22:50:09 INFO - TinderboxPrint: CPU usage
54.0% 22:50:09 INFO - TinderboxPrint: I/O read bytes / time
12,132,352 / 125 22:50:09 INFO - TinderboxPrint: I/O write bytes / time
507,759,616 / 1,738 22:50:09 INFO - TinderboxPrint: CPU idle
2,209.3 (46.0%) 22:50:09 INFO - TinderboxPrint: CPU system
960.6 (20.0%) 22:50:09 INFO - TinderboxPrint: CPU user
1,635.8 (34.0%) 22:50:09 INFO - TinderboxPrint: Swap in / out
247,218,176 / 0 22:50:09 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 197259776; Write bytes: 191034880; Read time: 15456; Write time: 415 22:50:09 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 11108352; Write bytes: 296883712; Read time: 107; Write time: 1266 22:50:09 INFO - Running post-run listener: _upload_blobber_files 22:50:09 INFO - Blob upload gear active. 22:50:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:50:09 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 22:50:09 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'] 22:50:09 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 22:50:09 INFO - (blobuploader) - INFO - Open directory for files ... 22:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:50:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:50:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:11 INFO - (blobuploader) - INFO - Done attempting. 22:50:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:50:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:50:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:12 INFO - (blobuploader) - INFO - Done attempting. 22:50:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:50:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:50:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:13 INFO - (blobuploader) - INFO - Done attempting. 22:50:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:50:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:50:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:50:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:50:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:50:15 INFO - (blobuploader) - INFO - Done attempting. 22:50:15 INFO - (blobuploader) - INFO - Iteration through files over. 22:50:15 INFO - Return code: 0 22:50:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:50:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:50:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43123aa21356c33038499bcbfc11760777673b4eb6a060185eb3c0fb51ff3789a90b907b412b3b94179d8e3c2b8731f2278d52b08a50a499fe73fde32ee5e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15c2e6305ca0d0b6bb22d31c417434144a749033eee970d2381f37679964faa14603cfd2f0a1327f15cc3acb98f9ec1ce56518eac65d02fc90cc8dfbbee84159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce578f5eed5a159fb483079bd959240cea0e11d5f6f8af64e01f6ec708444f15af5092dd11b6ede968acf6e170e5019d49c6cc4076f5c36422b619e4879b766", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f798939388c09b24a42df69aab14a9e47f750ec8c661088e0647e3bac193e6422fb1c509a829b670642c30f6435695fe43e0478aae1b9122cbc88edba4fd31dc"} 22:50:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:50:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:50:15 INFO - Contents: 22:50:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/43123aa21356c33038499bcbfc11760777673b4eb6a060185eb3c0fb51ff3789a90b907b412b3b94179d8e3c2b8731f2278d52b08a50a499fe73fde32ee5e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15c2e6305ca0d0b6bb22d31c417434144a749033eee970d2381f37679964faa14603cfd2f0a1327f15cc3acb98f9ec1ce56518eac65d02fc90cc8dfbbee84159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce578f5eed5a159fb483079bd959240cea0e11d5f6f8af64e01f6ec708444f15af5092dd11b6ede968acf6e170e5019d49c6cc4076f5c36422b619e4879b766", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f798939388c09b24a42df69aab14a9e47f750ec8c661088e0647e3bac193e6422fb1c509a829b670642c30f6435695fe43e0478aae1b9122cbc88edba4fd31dc"} 22:50:15 INFO - Running post-run listener: copy_logs_to_upload_dir 22:50:15 INFO - Copying logs to upload dir... 22:50:15 INFO - mkdir: /builds/slave/test/build/upload/logs 22:50:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.166644 ========= master_lag: 0.31 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-17 22:50:15.726759) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 22:50:15.730430) ========= 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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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/43123aa21356c33038499bcbfc11760777673b4eb6a060185eb3c0fb51ff3789a90b907b412b3b94179d8e3c2b8731f2278d52b08a50a499fe73fde32ee5e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15c2e6305ca0d0b6bb22d31c417434144a749033eee970d2381f37679964faa14603cfd2f0a1327f15cc3acb98f9ec1ce56518eac65d02fc90cc8dfbbee84159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce578f5eed5a159fb483079bd959240cea0e11d5f6f8af64e01f6ec708444f15af5092dd11b6ede968acf6e170e5019d49c6cc4076f5c36422b619e4879b766", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f798939388c09b24a42df69aab14a9e47f750ec8c661088e0647e3bac193e6422fb1c509a829b670642c30f6435695fe43e0478aae1b9122cbc88edba4fd31dc"} build_url:https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010991 build_url: 'https://queue.taskcluster.net/v1/task/CmOIbxBLRsOlaDshaZsvWg/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/43123aa21356c33038499bcbfc11760777673b4eb6a060185eb3c0fb51ff3789a90b907b412b3b94179d8e3c2b8731f2278d52b08a50a499fe73fde32ee5e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15c2e6305ca0d0b6bb22d31c417434144a749033eee970d2381f37679964faa14603cfd2f0a1327f15cc3acb98f9ec1ce56518eac65d02fc90cc8dfbbee84159", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce578f5eed5a159fb483079bd959240cea0e11d5f6f8af64e01f6ec708444f15af5092dd11b6ede968acf6e170e5019d49c6cc4076f5c36422b619e4879b766", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f798939388c09b24a42df69aab14a9e47f750ec8c661088e0647e3bac193e6422fb1c509a829b670642c30f6435695fe43e0478aae1b9122cbc88edba4fd31dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 22:50:15.765578) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 22:50:15.765897) ========= 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.Y1iO9M288n/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.ZvpqBkSzFI/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.005203 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 22:50:15.814640) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 22:50:15.814947) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 22:50:15.815250) ========= ========= Total master_lag: 0.45 =========