builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0193 starttime: 1492084676.78 results: success (0) buildid: 20170413030924 builduid: 35806fdf44d74879b0bb3d3cd0be3c4f revision: acf78d5d6ef2854753586cb5827a59e76ce22bb1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:56.776326) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:56.776771) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:56.777075) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:56.803708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:56.804082) ========= 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.HaJHQwIBRX/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.jaDA9n93dD/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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-13 04:57:56-- 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% 161M=0s 2017-04-13 04:57:56 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136943 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:56.965454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:56.965805) ========= 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.HaJHQwIBRX/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.jaDA9n93dD/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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.028638 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:57.016442) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 04:57:57.016813) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev acf78d5d6ef2854753586cb5827a59e76ce22bb1 --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 acf78d5d6ef2854753586cb5827a59e76ce22bb1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HaJHQwIBRX/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.jaDA9n93dD/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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-13 04:57:57,115 truncating revision to first 12 chars 2017-04-13 04:57:57,115 Setting DEBUG logging. 2017-04-13 04:57:57,115 attempt 1/10 2017-04-13 04:57:57,115 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/acf78d5d6ef2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 04:57:59,203 unpacking tar archive at: mozilla-aurora-acf78d5d6ef2/testing/mozharness/ program finished with exit code 0 elapsedTime=2.379451 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 04:57:59.420001) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:59.420379) ========= script_repo_revision: acf78d5d6ef2854753586cb5827a59e76ce22bb1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:59.420798) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:59.421069) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 04:57:59.440789) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-13 04:57:59.441138) ========= /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.HaJHQwIBRX/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.jaDA9n93dD/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:57:59 INFO - MultiFileLogger online at 20170413 04:57:59 in /builds/slave/test 04:57:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:57:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:57:59 INFO - {'append_to_log': False, 04:57:59 INFO - 'base_work_dir': '/builds/slave/test', 04:57:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:57:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:57:59 INFO - 'branch': 'Mozilla-Aurora', 04:57:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:57:59 INFO - 'config_files': ('talos/mac_config.py',), 04:57:59 INFO - 'default_actions': ('clobber', 04:57:59 INFO - 'read-buildbot-config', 04:57:59 INFO - 'download-and-extract', 04:57:59 INFO - 'populate-webroot', 04:57:59 INFO - 'create-virtualenv', 04:57:59 INFO - 'install', 04:57:59 INFO - 'run-tests'), 04:57:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:57:59 INFO - 'download_minidump_stackwalk': True, 04:57:59 INFO - 'download_symbols': 'ondemand', 04:57:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:57:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:57:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:57:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:57:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:57:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:57:59 INFO - 'gecko_profile': False, 04:57:59 INFO - 'gecko_profile_interval': 0, 04:57:59 INFO - 'installer_path': 'installer.exe', 04:57:59 INFO - 'log_level': 'info', 04:57:59 INFO - 'log_name': 'talos', 04:57:59 INFO - 'log_to_console': True, 04:57:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:57:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:57:59 INFO - 'opt_config_files': (), 04:57:59 INFO - 'pip_index': False, 04:57:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:57:59 INFO - 'cmd': ('bash', 04:57:59 INFO - '-c', 04:57:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:57:59 INFO - 'enabled': True, 04:57:59 INFO - 'halt_on_failure': False, 04:57:59 INFO - 'name': 'check_screen_resolution'},), 04:57:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:57:59 INFO - 'cmd': ('bash', 04:57:59 INFO - '-c', 04:57:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:57:59 INFO - 'enabled': True, 04:57:59 INFO - 'halt_on_failure': False, 04:57:59 INFO - 'name': 'check_screen_resolution'},), 04:57:59 INFO - 'run_cmd_checks_enabled': True, 04:57:59 INFO - 'suite': 'dromaeojs', 04:57:59 INFO - 'system_bits': '32', 04:57:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:57:59 INFO - 'title': 't-yosemite-r7-0193', 04:57:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:57:59 INFO - 'use_talos_json': True, 04:57:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:57:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:57:59 INFO - 'work_dir': 'build'} 04:57:59 INFO - [mozharness: 2017-04-13 11:57:59.653763Z] Running clobber step. 04:57:59 INFO - Running pre-action listener: _resource_record_pre_action 04:57:59 INFO - Running main action method: clobber 04:57:59 INFO - rmtree: /builds/slave/test/build 04:57:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:58:04 INFO - Running post-action listener: _resource_record_post_action 04:58:04 INFO - [mozharness: 2017-04-13 11:58:04.171418Z] Finished clobber step (success) 04:58:04 INFO - [mozharness: 2017-04-13 11:58:04.171588Z] Running read-buildbot-config step. 04:58:04 INFO - Running pre-action listener: _resource_record_pre_action 04:58:04 INFO - Running main action method: read_buildbot_config 04:58:04 INFO - Using buildbot properties: 04:58:04 INFO - { 04:58:04 INFO - "project": "", 04:58:04 INFO - "product": "firefox", 04:58:04 INFO - "script_repo_revision": "production", 04:58:04 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 04:58:04 INFO - "repository": "", 04:58:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 04:58:04 INFO - "buildid": "20170413030924", 04:58:04 INFO - "pgo_build": "False", 04:58:04 INFO - "basedir": "/builds/slave/test", 04:58:04 INFO - "buildnumber": 29, 04:58:04 INFO - "slavename": "t-yosemite-r7-0193", 04:58:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:58:04 INFO - "platform": "yosemite_r7", 04:58:04 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:58:04 INFO - "branch": "mozilla-aurora", 04:58:04 INFO - "repo_path": "releases/mozilla-aurora", 04:58:04 INFO - "revision": "acf78d5d6ef2854753586cb5827a59e76ce22bb1", 04:58:04 INFO - "stage_platform": "macosx64", 04:58:04 INFO - "builduid": "35806fdf44d74879b0bb3d3cd0be3c4f", 04:58:04 INFO - "slavebuilddir": "test" 04:58:04 INFO - } 04:58:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 04:58:04 INFO - Running post-action listener: _resource_record_post_action 04:58:04 INFO - [mozharness: 2017-04-13 11:58:04.175001Z] Finished read-buildbot-config step (success) 04:58:04 INFO - [mozharness: 2017-04-13 11:58:04.175126Z] Running download-and-extract step. 04:58:04 INFO - Running pre-action listener: _resource_record_pre_action 04:58:04 INFO - Running main action method: download_and_extract 04:58:04 INFO - mkdir: /builds/slave/test/build/tests 04:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:04 INFO - trying https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 04:58:04 INFO - Downloading https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 04:58:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 04:58:05 INFO - Downloaded 1199 bytes. 04:58:05 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 04:58:05 INFO - Using the following test package requirements: 04:58:05 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 04:58:05 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 04:58:05 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 04:58:05 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'jsshell-mac.zip'], 04:58:05 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 04:58:05 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 04:58:05 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 04:58:05 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 04:58:05 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 04:58:05 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 04:58:05 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 04:58:05 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:58:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 04:58:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:58:05 INFO - Fetch https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 04:58:07 INFO - Expected file size: 51790434 04:58:07 INFO - Obtained file size: 51790434 04:58:23 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:58:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 04:58:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:58:23 INFO - Fetch https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 04:58:24 INFO - Expected file size: 51790434 04:58:24 INFO - Obtained file size: 51790434 04:58:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 04:58:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:58:39 INFO - Fetch https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 04:58:40 INFO - Expected file size: 14404424 04:58:40 INFO - Obtained file size: 14404424 04:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:41 INFO - trying https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:58:41 INFO - Downloading https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 04:58:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 04:58:43 INFO - Downloaded 74729061 bytes. 04:58:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:58:43 INFO - mkdir: /builds/slave/test/properties 04:58:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:58:43 INFO - Writing to file /builds/slave/test/properties/build_url 04:58:43 INFO - Contents: 04:58:43 INFO - build_url:https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 04:58:44 INFO - Running post-action listener: _resource_record_post_action 04:58:44 INFO - Running post-action listener: set_extra_try_arguments 04:58:44 INFO - [mozharness: 2017-04-13 11:58:44.227153Z] Finished download-and-extract step (success) 04:58:44 INFO - [mozharness: 2017-04-13 11:58:44.227278Z] Running populate-webroot step. 04:58:44 INFO - Running pre-action listener: _resource_record_pre_action 04:58:44 INFO - Running main action method: populate_webroot 04:58:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:58:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:58:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:58:44 INFO - '--tppagecycles', 04:58:44 INFO - '7'], 04:58:44 INFO - 'tests': ['tsvgm']}}, 04:58:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:58:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:58:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:58:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:58:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:58:44 INFO - 'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:58:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:58:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:58:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:58:44 INFO - 'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['damp', 'tps']}, 04:58:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:58:44 INFO - 'tests': ['damp', 'tps']}, 04:58:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['dromaeo_dom']}, 04:58:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:58:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['basic_compositor_video']}, 04:58:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:58:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['a11yr', 04:58:44 INFO - 'ts_paint', 04:58:44 INFO - 'tpaint', 04:58:44 INFO - 'sessionrestore', 04:58:44 INFO - 'sessionrestore_no_auto_restore', 04:58:44 INFO - 'tabpaint']}, 04:58:44 INFO - 'other-e10s': {'tests': ['a11yr', 04:58:44 INFO - 'ts_paint', 04:58:44 INFO - 'tpaint', 04:58:44 INFO - 'sessionrestore', 04:58:44 INFO - 'sessionrestore_no_auto_restore', 04:58:44 INFO - 'tabpaint']}, 04:58:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:58:44 INFO - 'ts_paint', 04:58:44 INFO - 'tpaint', 04:58:44 INFO - 'sessionrestore', 04:58:44 INFO - 'sessionrestore_no_auto_restore', 04:58:44 INFO - 'tabpaint']}, 04:58:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:58:44 INFO - 'ts_paint', 04:58:44 INFO - 'tpaint', 04:58:44 INFO - 'sessionrestore', 04:58:44 INFO - 'sessionrestore_no_auto_restore', 04:58:44 INFO - 'tabpaint']}, 04:58:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['a11yr', 04:58:44 INFO - 'ts_paint', 04:58:44 INFO - 'tpaint', 04:58:44 INFO - 'sessionrestore', 04:58:44 INFO - 'sessionrestore_no_auto_restore', 04:58:44 INFO - 'tabpaint']}, 04:58:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['a11yr', 04:58:44 INFO - 'ts_paint', 04:58:44 INFO - 'tpaint', 04:58:44 INFO - 'sessionrestore', 04:58:44 INFO - 'sessionrestore_no_auto_restore', 04:58:44 INFO - 'tabpaint']}, 04:58:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['tsvgx', 04:58:44 INFO - 'tsvgr_opacity', 04:58:44 INFO - 'tart', 04:58:44 INFO - 'tscrollx', 04:58:44 INFO - 'cart', 04:58:44 INFO - 'tsvg_static']}, 04:58:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:58:44 INFO - 'tsvgr_opacity', 04:58:44 INFO - 'tart', 04:58:44 INFO - 'tscrollx', 04:58:44 INFO - 'cart', 04:58:44 INFO - 'tsvg_static']}, 04:58:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:58:44 INFO - 'talos_options': ['--disable-e10s'], 04:58:44 INFO - 'tests': ['tp5o']}, 04:58:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:58:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:58:44 INFO - 'talos_options': ['--disable-e10s', 04:58:44 INFO - '--xperf_path', 04:58:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:58:44 INFO - 'tests': ['tp5n']}, 04:58:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:58:44 INFO - 'talos_options': ['--xperf_path', 04:58:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:58:44 INFO - 'tests': ['tp5n']}}, 04:58:44 INFO - 'talos.zip': {'path': '', 04:58:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:58:44 INFO - Running post-action listener: _resource_record_post_action 04:58:44 INFO - [mozharness: 2017-04-13 11:58:44.245628Z] Finished populate-webroot step (success) 04:58:44 INFO - [mozharness: 2017-04-13 11:58:44.245764Z] Running create-virtualenv step. 04:58:44 INFO - Running pre-action listener: _resource_record_pre_action 04:58:44 INFO - Running main action method: create_virtualenv 04:58:44 INFO - Creating virtualenv /builds/slave/test/build/venv 04:58:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:58:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:58:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:58:44 INFO - Using real prefix '/tools/python27' 04:58:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:58:45 INFO - Installing distribute.............................................................................................................................................................................................done. 04:58:47 INFO - Installing pip.................done. 04:58:47 INFO - Return code: 0 04:58:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:58:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:58:48 INFO - Reading from file tmpfile_stdout 04:58:48 INFO - Output received: 04:58:48 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:58:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:58:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:58:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:58:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:48 INFO - 'HOME': '/Users/cltbld', 04:58:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:48 INFO - 'LOGNAME': 'cltbld', 04:58:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:48 INFO - 'MOZ_NO_REMOTE': '1', 04:58:48 INFO - 'NO_EM_RESTART': '1', 04:58:48 INFO - 'PAGER': '/bin/cat', 04:58:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:48 INFO - 'PWD': '/builds/slave/test', 04:58:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:48 INFO - 'SHELL': '/bin/bash', 04:58:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:58:48 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:58:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:48 INFO - 'USER': 'cltbld', 04:58:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:48 INFO - 'XPC_FLAGS': '0x0', 04:58:48 INFO - 'XPC_SERVICE_NAME': '0', 04:58:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:58:48 INFO - Cleaning up... 04:58:48 INFO - Return code: 0 04:58:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:58:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:58:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:58:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:48 INFO - 'HOME': '/Users/cltbld', 04:58:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:48 INFO - 'LOGNAME': 'cltbld', 04:58:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:48 INFO - 'MOZ_NO_REMOTE': '1', 04:58:48 INFO - 'NO_EM_RESTART': '1', 04:58:48 INFO - 'PAGER': '/bin/cat', 04:58:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:48 INFO - 'PWD': '/builds/slave/test', 04:58:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:48 INFO - 'SHELL': '/bin/bash', 04:58:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:58:48 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:58:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:48 INFO - 'USER': 'cltbld', 04:58:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:48 INFO - 'XPC_FLAGS': '0x0', 04:58:48 INFO - 'XPC_SERVICE_NAME': '0', 04:58:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:48 INFO - Downloading/unpacking psutil>=3.1.1 04:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:58:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:58:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:58:52 INFO - Installing collected packages: psutil 04:58:52 INFO - Running setup.py install for psutil 04:58:52 INFO - building 'psutil._psutil_osx' extension 04:58:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:58:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:58:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:58:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:58:53 INFO - building 'psutil._psutil_posix' extension 04:58:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:58:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:58:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:58:53 INFO - ^ 04:58:53 INFO - 1 warning generated. 04:58:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:58:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:58:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:58:53 INFO - Successfully installed psutil 04:58:53 INFO - Cleaning up... 04:58:53 INFO - Return code: 0 04:58:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:58:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:54 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:58:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:58:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:58:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:54 INFO - 'HOME': '/Users/cltbld', 04:58:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:54 INFO - 'LOGNAME': 'cltbld', 04:58:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:54 INFO - 'MOZ_NO_REMOTE': '1', 04:58:54 INFO - 'NO_EM_RESTART': '1', 04:58:54 INFO - 'PAGER': '/bin/cat', 04:58:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:54 INFO - 'PWD': '/builds/slave/test', 04:58:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:54 INFO - 'SHELL': '/bin/bash', 04:58:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:58:54 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:58:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:54 INFO - 'USER': 'cltbld', 04:58:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:54 INFO - 'XPC_FLAGS': '0x0', 04:58:54 INFO - 'XPC_SERVICE_NAME': '0', 04:58:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58: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 04:58:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:58:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:58:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:58:57 INFO - Installing collected packages: mozsystemmonitor 04:58:57 INFO - Running setup.py install for mozsystemmonitor 04:58:57 INFO - Successfully installed mozsystemmonitor 04:58:57 INFO - Cleaning up... 04:58:57 INFO - Return code: 0 04:58:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:58:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:58:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:58:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:58:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:58:57 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:58:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:58:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:58:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:58:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:58:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:58:57 INFO - 'HOME': '/Users/cltbld', 04:58:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:58:57 INFO - 'LOGNAME': 'cltbld', 04:58:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:58:57 INFO - 'MOZ_NO_REMOTE': '1', 04:58:57 INFO - 'NO_EM_RESTART': '1', 04:58:57 INFO - 'PAGER': '/bin/cat', 04:58:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:58:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:58:57 INFO - 'PWD': '/builds/slave/test', 04:58:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:58:57 INFO - 'SHELL': '/bin/bash', 04:58:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:58:57 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:58:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:58:57 INFO - 'USER': 'cltbld', 04:58:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:58:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:58:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:58:57 INFO - 'XPC_FLAGS': '0x0', 04:58:57 INFO - 'XPC_SERVICE_NAME': '0', 04:58:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:58:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:58:58 INFO - Downloading/unpacking jsonschema==2.5.1 04:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:58:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:59:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:01 INFO - Downloading functools32-3.2.3-2.tar.gz 04:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:59:01 INFO - warning: no files found matching '*.txt' 04:59:01 INFO - no previously-included directories found matching 'build' 04:59:01 INFO - no previously-included directories found matching 'dist' 04:59:01 INFO - no previously-included directories found matching '.git*' 04:59:01 INFO - Installing collected packages: jsonschema, functools32 04:59:01 INFO - Running setup.py install for functools32 04:59:01 INFO - warning: no files found matching '*.txt' 04:59:01 INFO - no previously-included directories found matching 'build' 04:59:01 INFO - no previously-included directories found matching 'dist' 04:59:01 INFO - no previously-included directories found matching '.git*' 04:59:01 INFO - Successfully installed jsonschema functools32 04:59:01 INFO - Cleaning up... 04:59:01 INFO - Return code: 0 04:59:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:01 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:59:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:59:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:59:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:01 INFO - 'HOME': '/Users/cltbld', 04:59:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:01 INFO - 'LOGNAME': 'cltbld', 04:59:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:01 INFO - 'MOZ_NO_REMOTE': '1', 04:59:01 INFO - 'NO_EM_RESTART': '1', 04:59:01 INFO - 'PAGER': '/bin/cat', 04:59:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:01 INFO - 'PWD': '/builds/slave/test', 04:59:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:01 INFO - 'SHELL': '/bin/bash', 04:59:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:59:01 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:59:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:01 INFO - 'USER': 'cltbld', 04:59:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:01 INFO - 'XPC_FLAGS': '0x0', 04:59:01 INFO - 'XPC_SERVICE_NAME': '0', 04:59:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:59:01 INFO - Cleaning up... 04:59:01 INFO - Return code: 0 04:59:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:01 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:59:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:59:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:59:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:01 INFO - 'HOME': '/Users/cltbld', 04:59:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:01 INFO - 'LOGNAME': 'cltbld', 04:59:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:01 INFO - 'MOZ_NO_REMOTE': '1', 04:59:01 INFO - 'NO_EM_RESTART': '1', 04:59:01 INFO - 'PAGER': '/bin/cat', 04:59:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:01 INFO - 'PWD': '/builds/slave/test', 04:59:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:01 INFO - 'SHELL': '/bin/bash', 04:59:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:59:01 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:59:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:01 INFO - 'USER': 'cltbld', 04:59:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:01 INFO - 'XPC_FLAGS': '0x0', 04:59:01 INFO - 'XPC_SERVICE_NAME': '0', 04:59:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:02 INFO - Downloading/unpacking blobuploader==1.2.4 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59: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 04:59:04 INFO - Downloading blobuploader-1.2.4.tar.gz 04:59:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:59:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:59:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:06 INFO - Downloading docopt-0.6.1.tar.gz 04:59:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:59:06 INFO - Installing collected packages: blobuploader, requests, docopt 04:59:06 INFO - Running setup.py install for blobuploader 04:59:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:59:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:59:06 INFO - Running setup.py install for requests 04:59:06 INFO - Running setup.py install for docopt 04:59:06 INFO - Successfully installed blobuploader requests docopt 04:59:06 INFO - Cleaning up... 04:59:07 INFO - Return code: 0 04:59:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:07 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:07 INFO - 'HOME': '/Users/cltbld', 04:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:07 INFO - 'LOGNAME': 'cltbld', 04:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:07 INFO - 'MOZ_NO_REMOTE': '1', 04:59:07 INFO - 'NO_EM_RESTART': '1', 04:59:07 INFO - 'PAGER': '/bin/cat', 04:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:07 INFO - 'PWD': '/builds/slave/test', 04:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:07 INFO - 'SHELL': '/bin/bash', 04:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:59:07 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:07 INFO - 'USER': 'cltbld', 04:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:07 INFO - 'XPC_FLAGS': '0x0', 04:59:07 INFO - 'XPC_SERVICE_NAME': '0', 04:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:59:07 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-oCMJRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:59:07 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Z40mby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:59:07 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-qqBEWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:59:07 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-m24M78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:59:07 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-7uzvMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:59:07 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-9YLoog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:59:07 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Fz2pjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-jDmtew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-ek5qnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-YVtZcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-K1XTZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-9oT9Lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-SNr2z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Co_6l_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-tdXTSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-4wwUdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:59:08 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Utwfk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:59:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:59:09 INFO - Running setup.py install for manifestparser 04:59:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:59:09 INFO - Running setup.py install for mozcrash 04:59:09 INFO - Running setup.py install for mozdebug 04:59:09 INFO - Running setup.py install for mozdevice 04:59:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:59:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:59:09 INFO - Running setup.py install for mozfile 04:59:09 INFO - Running setup.py install for mozhttpd 04:59:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:59:09 INFO - Running setup.py install for mozinfo 04:59:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:59:10 INFO - Running setup.py install for mozInstall 04:59:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:59:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:59:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:59:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:59:10 INFO - Running setup.py install for mozleak 04:59:10 INFO - Running setup.py install for mozlog 04:59:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:59:10 INFO - Running setup.py install for moznetwork 04:59:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:59:10 INFO - Running setup.py install for mozprocess 04:59:10 INFO - Running setup.py install for mozprofile 04:59:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:59:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:59:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:59:11 INFO - Running setup.py install for mozrunner 04:59:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:59:11 INFO - Running setup.py install for mozscreenshot 04:59:11 INFO - Running setup.py install for moztest 04:59:11 INFO - Running setup.py install for mozversion 04:59:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:59:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:59:11 INFO - Cleaning up... 04:59:11 INFO - Return code: 0 04:59:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:11 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:59:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:59:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:11 INFO - 'HOME': '/Users/cltbld', 04:59:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:11 INFO - 'LOGNAME': 'cltbld', 04:59:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:11 INFO - 'MOZ_NO_REMOTE': '1', 04:59:11 INFO - 'NO_EM_RESTART': '1', 04:59:11 INFO - 'PAGER': '/bin/cat', 04:59:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:11 INFO - 'PWD': '/builds/slave/test', 04:59:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:11 INFO - 'SHELL': '/bin/bash', 04:59:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:59:11 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:59:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:11 INFO - 'USER': 'cltbld', 04:59:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:11 INFO - 'XPC_FLAGS': '0x0', 04:59:11 INFO - 'XPC_SERVICE_NAME': '0', 04:59:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:59:11 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-Yevl_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-7q25f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-eFdjJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-ifW9mI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-WKCbG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-WCi44U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-8z_cY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-rE2VsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-OeWNOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-9fznKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:59:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:59:12 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-MU2Bjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:59:13 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-9GZbWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:59:13 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-NYn_M5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:59:13 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-IxsG5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:13 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-NzAjJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:59:13 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-7KQ1xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:59:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:59:13 INFO - Running setup.py (path:/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/pip-53ryvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:59:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:59:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:59:16 INFO - Downloading blessings-1.6.tar.gz 04:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:59:16 INFO - Installing collected packages: blessings 04:59:16 INFO - Running setup.py install for blessings 04:59:16 INFO - Successfully installed blessings 04:59:16 INFO - Cleaning up... 04:59:16 INFO - Return code: 0 04:59:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:59:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:59:17 INFO - Reading from file tmpfile_stdout 04:59:17 INFO - Current package versions: 04:59:17 INFO - blessings == 1.6 04:59:17 INFO - blobuploader == 1.2.4 04:59:17 INFO - docopt == 0.6.1 04:59:17 INFO - functools32 == 3.2.3-2 04:59:17 INFO - jsonschema == 2.5.1 04:59:17 INFO - manifestparser == 1.1 04:59:17 INFO - mozInstall == 1.12 04:59:17 INFO - mozcrash == 1.0 04:59:17 INFO - mozdebug == 0.1 04:59:17 INFO - mozdevice == 0.49 04:59:17 INFO - mozfile == 1.2 04:59:17 INFO - mozhttpd == 0.7 04:59:17 INFO - mozinfo == 0.9 04:59:17 INFO - mozleak == 0.1 04:59:17 INFO - mozlog == 3.4 04:59:17 INFO - moznetwork == 0.27 04:59:17 INFO - mozprocess == 0.25 04:59:17 INFO - mozprofile == 0.28 04:59:17 INFO - mozrunner == 6.13 04:59:17 INFO - mozscreenshot == 0.1 04:59:17 INFO - mozsystemmonitor == 0.3 04:59:17 INFO - moztest == 0.8 04:59:17 INFO - mozversion == 1.4 04:59:17 INFO - psutil == 3.1.1 04:59:17 INFO - requests == 1.2.3 04:59:17 INFO - wsgiref == 0.1.2 04:59:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:17 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:17 INFO - 'HOME': '/Users/cltbld', 04:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:17 INFO - 'LOGNAME': 'cltbld', 04:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:17 INFO - 'MOZ_NO_REMOTE': '1', 04:59:17 INFO - 'NO_EM_RESTART': '1', 04:59:17 INFO - 'PAGER': '/bin/cat', 04:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:17 INFO - 'PWD': '/builds/slave/test', 04:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:17 INFO - 'SHELL': '/bin/bash', 04:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:59:17 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:17 INFO - 'USER': 'cltbld', 04:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:17 INFO - 'XPC_FLAGS': '0x0', 04:59:17 INFO - 'XPC_SERVICE_NAME': '0', 04:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:59:17 INFO - Cleaning up... 04:59:17 INFO - Return code: 0 04:59:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:59:17 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 0x10ed7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ed81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb63856a200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, '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.HaJHQwIBRX/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.jaDA9n93dD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:17 INFO - 'HOME': '/Users/cltbld', 04:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:17 INFO - 'LOGNAME': 'cltbld', 04:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:17 INFO - 'MOZ_NO_REMOTE': '1', 04:59:17 INFO - 'NO_EM_RESTART': '1', 04:59:17 INFO - 'PAGER': '/bin/cat', 04:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:17 INFO - 'PWD': '/builds/slave/test', 04:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:17 INFO - 'SHELL': '/bin/bash', 04:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:59:17 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:17 INFO - 'USER': 'cltbld', 04:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:17 INFO - 'XPC_FLAGS': '0x0', 04:59:17 INFO - 'XPC_SERVICE_NAME': '0', 04:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:59:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:59:17 INFO - Cleaning up... 04:59:17 INFO - Return code: 0 04:59:17 INFO - Running post-action listener: _resource_record_post_action 04:59:17 INFO - Running post-action listener: _start_resource_monitoring 04:59:17 INFO - Starting resource monitoring. 04:59:17 INFO - [mozharness: 2017-04-13 11:59:17.635239Z] Finished create-virtualenv step (success) 04:59:17 INFO - [mozharness: 2017-04-13 11:59:17.636232Z] Running install step. 04:59:17 INFO - Running pre-action listener: _resource_record_pre_action 04:59:17 INFO - Running main action method: install 04:59:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:59:17 INFO - Reading from file tmpfile_stdout 04:59:17 INFO - Detecting whether we're running mozinstall >=1.0... 04:59:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:59:18 INFO - Reading from file tmpfile_stdout 04:59:18 INFO - Output received: 04:59:18 INFO - Usage: mozinstall [options] installer 04:59:18 INFO - Options: 04:59:18 INFO - -h, --help show this help message and exit 04:59:18 INFO - -d DEST, --destination=DEST 04:59:18 INFO - Directory to install application into. [default: 04:59:18 INFO - "/builds/slave/test"] 04:59:18 INFO - --app=APP Application being installed. [default: firefox] 04:59:18 INFO - mkdir: /builds/slave/test/build/application 04:59:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:59:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:59:38 INFO - Reading from file tmpfile_stdout 04:59:38 INFO - Output received: 04:59:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:59:38 INFO - Running post-action listener: _resource_record_post_action 04:59:38 INFO - [mozharness: 2017-04-13 11:59:38.026208Z] Finished install step (success) 04:59:38 INFO - [mozharness: 2017-04-13 11:59:38.026365Z] Running run-tests step. 04:59:38 INFO - Running pre-action listener: _resource_record_pre_action 04:59:38 INFO - Running main action method: run_tests 04:59:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:59:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:59:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:59:38 INFO - 2017-04-13 04:59:38.040 screenresolution[1618:7412] starting screenresolution argv=screenresolution get 04:59:38 INFO - 2017-04-13 04:59:38.043 screenresolution[1618:7412] Display 0: 1600x1200x32@60 04:59:38 INFO - 2017-04-13 04:59:38.049 screenresolution[1619:7415] starting screenresolution argv=screenresolution list 04:59:38 INFO - Available Modes on Display 0 04:59:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:59:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:59:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:59:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:59:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:59:38 INFO - Intel Iris: 04:59:38 INFO - Chipset Model: Intel Iris 04:59:38 INFO - Type: GPU 04:59:38 INFO - Bus: Built-In 04:59:38 INFO - VRAM (Dynamic, Max): 1536 MB 04:59:38 INFO - Vendor: Intel (0x8086) 04:59:38 INFO - Device ID: 0x0a2e 04:59:38 INFO - Revision ID: 0x0009 04:59:38 INFO - Displays: 04:59:38 INFO - Display: 04:59:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:59:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:59:38 INFO - Display Serial Number: 1600x1200 60 04:59:38 INFO - Main Display: Yes 04:59:38 INFO - Mirror: Off 04:59:38 INFO - Online: Yes 04:59:38 INFO - Rotation: Supported 04:59:38 INFO - Return code: 0 04:59:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:59:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:59:38 INFO - Python 2.7.3 04:59:38 INFO - Return code: 0 04:59:38 INFO - grabbing minidump binary from tooltool 04:59:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:59:38 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 0x10eb5cd30>, '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 0x10ea83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb51be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:59:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:59:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:59:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:59:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:59:38 INFO - Return code: 0 04:59:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:59:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:59:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:59:38 INFO - ENV: RUST_BACKTRACE is now 1 04:59:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:59:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:59:38 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/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0193', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:59:38 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/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0193 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:59:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HaJHQwIBRX/Render', 04:59:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:59:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:59:38 INFO - 'HOME': '/Users/cltbld', 04:59:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:59:38 INFO - 'LOGNAME': 'cltbld', 04:59:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:59:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:59:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:59:38 INFO - 'MOZ_NO_REMOTE': '1', 04:59:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:59:38 INFO - 'NO_EM_RESTART': '1', 04:59:38 INFO - 'PAGER': '/bin/cat', 04:59:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:59:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:59:38 INFO - 'PWD': '/builds/slave/test', 04:59:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:59:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:59:38 INFO - 'RUST_BACKTRACE': '1', 04:59:38 INFO - 'SHELL': '/bin/bash', 04:59:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jaDA9n93dD/Listeners', 04:59:38 INFO - 'TMPDIR': '/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/', 04:59:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:59:38 INFO - 'USER': 'cltbld', 04:59:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:59:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:59:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:59:38 INFO - 'XPC_FLAGS': '0x0', 04:59:38 INFO - 'XPC_SERVICE_NAME': '0', 04:59:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:59:38 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/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0193', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:59:38 INFO - mozversion application_buildid: 20170413030924 04:59:38 INFO - mozversion application_changeset: acf78d5d6ef2854753586cb5827a59e76ce22bb1 04:59:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:59:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:59:38 INFO - mozversion application_name: Firefox 04:59:38 INFO - mozversion application_remotingname: firefox-dev 04:59:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:59:38 INFO - mozversion application_vendor: Mozilla 04:59:38 INFO - mozversion application_version: 54.0a2 04:59:38 INFO - mozversion platform_buildid: 20170413030924 04:59:38 INFO - mozversion platform_changeset: acf78d5d6ef2854753586cb5827a59e76ce22bb1 04:59:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:59:38 INFO - mozversion platform_version: 54.0a2 04:59:38 INFO - using testdate: 1492084778 04:59:38 INFO - actual date: 1492084778 04:59:38 INFO - starting webserver on 'localhost:49244' 04:59:38 INFO - SUITE-START | Running 2 tests 04:59:38 INFO - TEST-START | dromaeo_css 04:59:38 INFO - operating with platform_type : mac_ 04:59:38 INFO - Initialising browser for dromaeo_css test... 04:59:38 INFO - TEST-INFO | started process 1629 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmp4hfKuE/profile http://localhost:49244/getInfo.html) 04:59:41 INFO - PROCESS | 1629 | __metrics Screen width/height:1600/1200 04:59:41 INFO - PROCESS | 1629 | colorDepth:24 04:59:41 INFO - PROCESS | 1629 | Browser inner width/height: 1024/689 04:59:41 INFO - PROCESS | 1629 | __metrics 04:59:41 INFO - PROCESS | 1629 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:59:41 INFO - PROCESS | 1629 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:59:42 INFO - TEST-INFO | 1629: exit 0 04:59:42 INFO - Browser initialized. 04:59:42 INFO - Running cycle 1/1 for dromaeo_css test... 04:59:42 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmp4hfKuE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:59:54 INFO - PROCESS | 1631 | RSS: Main: 214736896 04:59:54 INFO - PROCESS | 1631 | 05:02:57 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:03:00 INFO - PROCESS | 1631 | RSS: Main: 429469696 05:03:00 INFO - PROCESS | 1631 | 05:04:43 INFO - PROCESS | 1631 | ************************* 05:04:43 INFO - PROCESS | 1631 | A coding exception was thrown and uncaught in a Task. 05:04:43 INFO - PROCESS | 1631 | 05:04:43 ERROR - PROCESS | 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:04:43 INFO - PROCESS | 1631 | Full stack: 05:04:43 INFO - PROCESS | 1631 | ************************* 05:04:43 INFO - PROCESS | 1631 | ************************* 05:04:43 INFO - PROCESS | 1631 | A coding exception was thrown and uncaught in a Task. 05:04:43 INFO - PROCESS | 1631 | 05:04:43 ERROR - PROCESS | 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:04:43 INFO - PROCESS | 1631 | Full stack: 05:04:43 INFO - PROCESS | 1631 | ************************* 05:04:43 INFO - PROCESS | 1631 | ************************* 05:04:43 INFO - PROCESS | 1631 | A coding exception was thrown and uncaught in a Task. 05:04:43 INFO - PROCESS | 1631 | 05:04:43 ERROR - PROCESS | 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:04:43 INFO - PROCESS | 1631 | Full stack: 05:04:43 INFO - PROCESS | 1631 | ************************* 05:04:43 INFO - PROCESS | 1631 | ************************* 05:04:43 INFO - PROCESS | 1631 | A coding exception was thrown and uncaught in a Task. 05:04:43 INFO - PROCESS | 1631 | 05:04:43 ERROR - PROCESS | 1631 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:04:43 INFO - PROCESS | 1631 | Full stack: 05:04:43 INFO - PROCESS | 1631 | ************************* 05:06:03 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 05:06:04 INFO - PROCESS | 1631 | RSS: Main: 353058816 05:06:04 INFO - PROCESS | 1631 | 05:09:06 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:09:07 INFO - PROCESS | 1631 | RSS: Main: 338563072 05:09:07 INFO - PROCESS | 1631 | 05:12:09 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:12:10 INFO - PROCESS | 1631 | RSS: Main: 319541248 05:12:10 INFO - PROCESS | 1631 | 05:15:13 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 05:15:14 INFO - PROCESS | 1631 | RSS: Main: 327086080 05:15:14 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 05:18:18 INFO - PROCESS | 1631 | RSS: Main: 378978304 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | __start_tp_report 05:18:18 INFO - PROCESS | 1631 | _x_x_mozilla_page_load 05:18:18 INFO - PROCESS | 1631 | _x_x_mozilla_page_load_details 05:18:18 INFO - PROCESS | 1631 | |i|pagename|runs| 05:18:18 INFO - PROCESS | 1631 | |0;dojo.html;3293.604767427909;3393.813340266285;3401.40475416802;3421.7946923184627;3439;5917.763289468421;5986.551008674356;5993.760249590011;6002.699865006754;6032.547558933075;5950.5834591578605;5989.730462129204;6018.729157187926;6033.7284822183;6040.063790112539;5370.946290537094;5404.270423492837;5418.431064738204;5432.67403955762;5514.476124768141;5308.071087559681;5308.203769434577;5351.357837059559;5441.319835020622;5481.040817856859;5433.510984011438;5450.236966824647;5457.781688732446;5470.124780035195;5479.917801232985;5472.863178420532;5485.807996720096;5490.56075513958;5493.011257973563;5499.945000550003;5224.981128679909;5229.032628963616;5390.838274851761;5410.513053825174;5435.510804027617;5812.070068789016;5824.30108386992;5831.854203644901;5835.649861008353;5847.122931560258;6241.157443745082;6276.560640755149;6306.779262725829;6325.683715814191;6360.173177486943;6107.84730381744;6345.619262844244;6399.232092148972;6412.871742565123;6425.421712045892;6120.357362476965;6128.233970753611;6132.6933653317155;6153.569250152491;6159.168512250879;6222.471089957314;6283.937160628335;6290.842728931813;6569.343065693488;6575.934240657532;6255.937440625627;6261.092141639528;6268.4671802897265;6273.686315684197;6275.497960163176;5374.54316383104;6369.904451433232;6378.202724659418;6382.425581697669;6393.904091438632;6285.120083188357;6319.146915166486;6329.746810127635;6335.556511044183;6358.904616430757;6249.0938813871735;6315.810525684236;6339.112524246517;6362.172917520693;6365.649889256136;5052.368453943257;5099.898002039939;5100.744962751922;5119.10415677255;5167.250748641502;4591.77041147948;4646.628269738413;4674.766261686902;4687.718736875799;4696.577308042292;5780.913286300709;5795.82612521625;5820.534357251409;5965.791197308074;6061.818145455643;6325.272593651789;6391.616503009741;6411.942029565163;6436.388543088399;6439.9356006440275;5378.139497680352;5970.194023806731;6029.638221706711;6040.244969378828;6063.787767428119;5462.289902312754;5489.451054894478;5612.298462692163;5644.491995720405;5647.350554686263;6371.681415929185;6499.967500162469;6541.96729016371;6583.967080164569;6632.801015969529;6417;6421.486281097407;6422.325655806073;6428.035794630843;6444.355564443518;5581.609287349846;6580.473562114924;6618.305077966744;6632.734690612321;6656.068150459037;6207.503399728102;6260.373962603703;6288.6855657217875;6301.96849015752;6369.585976911486;6223.128761973418;6362.172917520693;6365.745370185326;6382.872342553216;6394.584352017104;6268.435840774352;6295.96852015737;6298.08677741734;6316.494680425647;6338.714757835816;6134.467201660849;6167.938320616916;6226.688665566613;6329.335419780857;6370.8088757337355;6097.573090410549;6122.479589234965;6127.417895299939;6127.571070025056;6141.109539116714;5460.208269800936;6191.938080619136;6199.783007594712;6242.094896240111;6318.968405157945;6238.844028899132;6255.46828519581;6287.2769631492965;6293.118963345227;6301.747930082745 05:18:18 INFO - PROCESS | 1631 | |1;ext.html;794.1700922535949;813.8982627171605;820.7414664380716;843.7848348671088;882.1927935938617;38226.04434889126;39122.80438597807;40702.38946415803;40875.386869196984;40923.79538102313;39183.608163918354;39377.60622393775;39439.60560394395;39451.210975780465;39636.801815990955;7278.563286202845;9006.504642244674;9143.862842057375;9144.039875813043;9157.175854173123;7372.115346158468;10719.410432426213;11006.394648294341;11007.834882476769;11012.008919197271;10965.50655220513;10974.286671366375;10980.890191098106;11005.229633925628;11036.392998385125;7187.604681742502;9778.853317200177;10506.474676266233;10918.945405272963;10943.99999999996;7159.67781449836;10596.682099536949;10873.238873278873;10926;10986.560537578487;37858.62141378578;38151.04622384435;38153.809230953666;38168.23663526714;38199.854004379915;3566.1084728817796;3585.354636165489;3777.7922214278206;3784.375578029622;3842.9231415371537;23924;24140.63789043166;24423.87788061066;24969.75151242449;25090.37274068163;3045.299581096338;3136.184592006072;3152.3064925716308;3160.952585711216;3206.3907857506997;326488;327921;328023.35988319904;329083.9999999976;329188;36334.63665363366;37068.44397334042;42860.35709464361;42980.57019429766;43052;38273;44214.23143074306;44721.4347497836;44978.55021449744;45203.54796452059;12777.872221277668;12829.23024618526;12849;12853.807192892114;12860.099793014399;10861.565537378416;10865.348079115278;10877.564897404174;10896.40069796153;10915.345079295268;9662.56518456671;11477.827832582518;11539.711507212387;11605.245659032136;11728.413579321;5579.497845193952;5617.129344951457;5632.774689012475;5647.943520564824;5677.829665110054;1080.5353697909975;1093.2893619147617;1098.549594666199;1131.3494740524231;1170.280277629252;24167.033318667407;26594.734052659227;26931;27431.451370972467;27668;1163.2206421697483;1165.755558421724;1166.8658104317942;1195.6950977500683;1212.739261058859;13935.303234838217;13976.720465590835;14195.503157389649;14235.359408826627;14235.572932812032;480777;482938;483693;483875;487077.5646121747;55176.448235517935;55575.444245557024;55628.44371556232;55664.44335556593;55773;1417.185118556834;1473.035161968912;1473.7936688863354;1475.5057055886184;1478.8964772465824;25034.37414064663;25900.093496727524;26330.078447254255;26379.60430593581;26616.334591635365;40941.59058409497;41037.38443923284;41046.1790764189;41113.79443102765;41247.762567123034;435728;436290;436377;437298;437978;30354;30846.53730194004;30997.53503697401;31054.22364440907;31083.533746994264;42669.933251668954;43519.34720979251;43733.34399983939;43791;43840.561594384926;28052.85973570119;29549.85225073947;29571.85214073916;29787.404251915275;30502.084937451913;29562.852185738935;30126.69873301239;30395.54406683946;30462.84768576143;30519.542206867358;35221.82389088038;35832.74585389499;35987.64012359948;35994.28011439704;36217.91346259617;27223.455530889667;29451.11646650604;29901.551476728302;30333.696663033086;30642;21020.474488137308;23290.53418931578;23419.180328688413;23495.88252058729;24033.519329612962 05:18:18 INFO - PROCESS | 1631 | |2;jquery.html;392.1196912930468;396.27877263380645;417.8955261184704;422.0882892951225;461.287311104344;12907.677308067303;14702.41190352386;20847.166113355484;21012;21648.675269870924;21831.781682183173;22009.559808803813;22317.77682223177;22928.885355573242;23225.883870580623;6437.9678101609425;6679.899801502981;6723.159605049369;6787.592744435325;6795.286494918037;6442.420182183602;6640.535162538624;6678.165229346332;6698.8995165072565;6730.293319201487;6716.395524402803;6716.46268298535;6717.6977036033495;6718.83202919926;6729.2597814240635;6691.933080669204;7130.286971302906;7438.367738742213;7634.770956871303;7641.23587641237;7025.718971241145;7242.782716518513;7342.229065948105;7360.852782944311;7378.815529611803;25481.853316600613;25699.48601027969;25910.481790364087;26311;26567.202983910513;2697.4874773792917;2935.7504612107996;3284;3377.4258376076127;3418.2479854431986;14828;14933.70132597342;15147.166905820175;15191.696166076727;15209.315580798891;1842.5762074722888;1854.8701590888643;1893.810618938109;1897.766140169393;1938.9709154362695;153254;157171.99999999886;162415.3758462412;163030;164239;19124;19444.458279583636;19536.12087456068;19693.606127877363;19729.77161052322;31466.842665786524;31590.368192636477;31739.68260317367;31777.046688598915;31786.523202151988;9465.100815422662;9689.41863488193;9837.311388202757;9949.651762188434;10006.849897251546;8114.148014458515;8123.512589244546;8126.837463250702;8251.29863961558;8288.088310285864;9238.399504032217;9333.439993600407;9467.337286389886;9468.810623787485;9486.95256523713;3492.738044646611;3905.8776806989786;4056.817443215062;4092.5062352241216;4106.445629839993;881.6649673124172;891.9286457083417;892.4600616626977;910.1944778870615;910.7540963939832;20228.393148205578;20492.69260961087;20585.89707051455;20866.47833804167;20867.686984695243;709.726755199252;920.5535315372039;921.9861702074475;939.1359948847077;948.9525523723786;8717.302615790848;10319.948400258101;10754.462276886125;10836.837447438136;10896.237263391646;729468;736292;742466;749201;760653.1967340128;85322;135148;147504.26247868905;152293;157681;994.472929347447;1185.3658292813475;1211.9212251203644;1213.9028877689764;1214.6356093172012;18220.45338639843;18344.72482912784;19854;19974.50063748418;19986.500337491678;62851;63349.68325158345;63565.68217158884;63948;64015.679921600095;87285.56357218174;88304;88534;90088;90163.19673606622;7816.9447124637445;8599.656013759379;9211.815763684823;10727.731806704895;10939.671809845717;10622.681319560426;11852.644420667393;12070.249813777129;12093.879061209274;12163;9648.903510964801;10150.137238334824;10213.234007449322;10277.743056423351;10409.9479502602;9713.174380177765;9809.852852207365;9990.950045250018;10047.145992590711;10078.395296282246;10609.257351985596;10737.516811743646;10771.353718776898;10779.02988731018;10827.67516974492;10831.783364332512;10914.454277286262;10941.945290273816;10946.715985204248;11113.22207445471;10192.541335640062;10472.319299245524;10514.316569422963;10518.211134165122;10529.26295159362 05:18:18 INFO - PROCESS | 1631 | |3;mootools.html;1570.2619768708703;1582.4461438496528;1604.9518514444565;1614.0396464103853;1623.8700903927686;10507.579696812129;11072.501737421815;11083.833742493869;11093.445327733602;11113.666590002304;10733.83899241512;10853.565857365717;11179.552817887296;11322;11335.489902954367;2202.744435671668;2319.026009076192;2404.398900274936;2439.2438344113293;2448.4980578981267;2326.3951372643064;2407.313915534073;2435.1355268879556;2437.878106094688;2438.85366877987;2317.733460652021;2376.275236053003;2380.1788383007834;2397.2688330059327;2404.843685160459;2276.6926464927355;2326.162581470669;2329.5923213437663;2334.4280651240556;2402.6396040593877;2389.522095580891;2402.4594466244953;2403.062805505854;2417.867017314047;2417.987910060456;9511.57197926095;9563.474008929506;11028.172887033505;11031.393273369962;11053.889461105366;5654.660720356792;5811.941880581182;5822.912656310159;5826.883462330729;5828.329742079631;3544.7518673692853;3590.0486371111674;3630.3283892479976;3653.8721144759807;3666.4133738601963;1091.5851976249055;1197.2038594334756;1209.7278112424722;1241.5096037065398;1264.6332563556557;12408.821161989597;13667.589972300846;14016.509422170277;15234.466793662279;15460.995035322669;3968.4245784361106;4181.832726690959;4350.978245108754;4355.562664320766;4366.803493842721;10501.21240906935;11093.83359249612;11347.943260283811;11554.653360399201;11558.711032224262;5482.533984611273;5539.861503462445;5547.195656629766;5548.861278468071;5552.444755524496;4975.0796102721115;5140.434552199255;5192;5234.3718753749035;5273.419923808378;3278.213228825065;3819.236152769457;3842.654161125512;3919.8240527841535;3927.9018024549046;1025.1388833379997;1026.1431704526653;1041.317936751429;1041.3595638682307;1047.4553232319151;715.4955756191872;789.3290702902601;790.7430085222326;809.4091313341294;827.2678679368802;6481.902771458432;6550.6069635822005;6628.502862285348;6661.233958094881;6750.493712971547;919.7102912582515;972.3388096094582;999.6601155607128;1005.2360206243162;1049.9790004199874;4065.4308396824467;4160.771157586301;4202.222588821078;4317.11499142675;4323.351497275371;13581.524646637321;14157.00900936945;14172.362243699059;14193.716125677429;14243.430262789372;11661.183717139718;14100.85899141016;14103.224322662147;14104.294785260696;14114.082584631966;8724.781880452912;9765.267604929659;9989.550470228713;10159.796804063877;10470.638816953748;5657.745401456862;6629.337066293525;6736.326367363225;6808;6822.658867056726;11899.048076153715;12093.939530302292;12120.75758484843;12132.818007729715;12186.268823870596;12228.816567751668;12425.751484970431;12564.87435125637;12602.054845886778;12624.74750505003;8319.168083191633;8570.700025499327;8570.871436928575;8570.957145214483;8571.914280857112;8941.642334306554;10015.949920250352;10428.582856685647;10545.156387488829;10550.89449105499;7722.961385193263;8650.56747162652;9047.004829468622;9117.954410227907;9405.43567385959;8868.068852770624;9069.77325566866;9103.954480227821;9230.538473076454;9366.531673416166;7641.2740789624895;7786.727464538941;7788.182240864628;7801.063872335129;7810.453268271166;5814.5639077068545;5828.562857785768;5842.853928651818;6009.158717779602;6046.2139921809885;5895.557833162444;5932.849490578921;5944.464998150187;6193.225846769154;6286.654234017173 05:18:18 INFO - PROCESS | 1631 | |4;prototype.html;381.24513463342583;382.09824813440287;383.1972018620988;391.4715134568331;392.68585131894486;4854.931914978708;4862.902741945159;5131.794728210872;5158.529819001586;5178.824893544711;4188.680565621827;4393.912121757571;5081.129162023454;5108.974455127728;5123.359580052494;3539.752217344787;3547.290541891619;3979.801009949491;4015.687058177001;4116.34138537834;4051.4733084699096;4089.407035979781;4099.995501102234;4123.237201117803;4156;4116.8970775730695;4129.9174016519655;4134.4625198724125;4144.23331683638;4158.480189976253;3844.9042023023585;3938.763674179544;3960.782156981365;4072.3077076897;4081.8571350002903;3621.692156166729;3630.528031355907;3632.2928223735066;3636.4727114568504;3648.7081033517256;4252.617264446215;4496.5278645742055;4996.675216110941;5008.524190201925;5035.672681275705;3965.543962444327;4057.2899742544937;4321.437484402296;4508.887277818048;4683.063387322515;4807.8124453373985;5159.477954003578;5209.7134657593815;5314.707691076989;5357.7589008494715;3899.972014552417;3993.3061917725986;4022;4043.2317859606583;4059.776712280823;4905.043516514281;4952.796005777466;5623.409541998113;5846.239988801472;5850.3564607893095;4317.294668605883;4743.651892313327;4950.381202349707;4960.098342469696;5068.797248110034;4196.5803419658405;4407.228734971432;4725.47187254817;4947.180107338782;5092.26162206478;4704.741239231875;5028.974855125774;5247;5269.288646032813;5289.947100528945;4655.716740598657;4989.553029621442;5545.140503221926;5593.440655934374;5610;1369.0621923982103;1437.324457504971;1491.6196369925817;1492.3881208704597;1590.8647764939878;1101.6144349477618;1113.0872684398878;1140.5002421815286;1178.8585369755683;1206.5897594817804;4427.5572442755465;4710.69380490267;5058.079988009322;5241.449647786927;5278.155495120763;5786.42135786418;5801.7099145042575;5804.65172089676;5821.76712931478;5841.386654401312;4451.933221001687;4660.7669616519715;4796.37647105874;5062.367204099488;5182.714950677749;2641.556560181824;2857.6142220799925;2998.995336562276;3002.489576771954;3024.909252722422;4969.82534670871;5290;5488.9725551372785;5897.882042359129;6002.969985150134;4584.899624090194;5103.821366252238;5151.4333423323405;5580.832575022756;5854.058130997041;5453;5486.067368547357;5784.913226301693;5890.793822216202;5980.461758441674;4914.115459217375;5334.439883812144;5461.909970383073;5790.94209057904;5880.676562788916;4774.474807771212;4929.679570827885;4999.500049994971;5151.5106064923775;5491.956528259618;4977.527134922177;5470.261514695465;5652.293463317085;6072.449571180203;6125.151804410971;3638.890833275006;3739.45777862203;4025.8993525162105;4437.378766972691;4621;5024.321716568216;5685.03866166172;5732.828015159552;5804.506616937608;5806.941930580809;5212.244224587489;5475.561955043667;5580.944190558205;5618.606697531297;5664.546836253007;4774.904501909873;5082.186850103965;5219.634625576173;5325.467453254799;5470.64440811346;5102.4642412546145;5118.309028281284;5377.677339359651;5469.972650136724;5526.696031718294;5407.510597821966;5451.337342110694;5617.9719101404235;5677.6373670318;5691.544676425959;4774.737389443614;5187.031920580835;5301.946980530145;5443.013300145498;5542.334919809648 05:18:18 INFO - PROCESS | 1631 | |5;yui.html;656.297761395307;701.3792793377859;731.4404480572363;775.3990657240641;806.1656185847648;14213.786793198098;15053.473128440506;15128.243587820592;15141.545753627395;15161.090334579932;13506.189628622265;13695.452181912711;13882.236476993761;13892.74965253127;13912.652183695413;939.544321004314;945.1115951006067;963.1331801378745;963.5712108111893;964.3827950111898;949.710338346804;950.7005293332578;951.6050838901847;953.7806304549957;954.0268925695786;1011.6054738651932;1011.8178727828988;1013.7262939006464;1013.7465633591602;1016.2987538598344;1007.1841808135433;1009.8990100989915;1010.1111022300329;1011.812814629296;1011.9443430611313;751.6655088485626;752.0073502976073;752.8833030880222;754.3324158120048;755.3390783064818;12650.177738446971;12847.29339886306;12940.805887911689;13518.932405338008;13760.247950409857;11099.667009989713;11383.430828458544;11598.188126831124;11698;12097.758044839054;10311.84532232017;10411.791764164751;10612.522436490377;10619;10691.83962240567;3493.1441796759705;3539.663731945461;3743.8689645862537;3768.8680896168503;3775.6035616260174;104467.95532044658;109388.26529336751;110880.4455977723;111465.44267278693;113728;8715.346349023848;8749.180881272398;8908.198262156437;9021.50381728998;9028.458292502472;16623.750643740354;18502.074896255133;18905.14926828296;18912.621747564972;18962.905185473985;7224.927750722531;7276.100792051507;7525.2098529654695;7531.623418829036;7613.847723045508;3659.524261845943;4232.619064284229;4299.871003869889;4300.569943005675;4340.804663790137;3327.5175099610906;3423.4864770284157;3449.586049674055;3450.741194410379;3451.137215696076;619.4332186049804;624.2664868779166;638.9265234497999;646.5603389695052;647.9740810367626;655.8360409897525;662.1656712542232;673.2291526202471;683.7128406069463;716.2658275267892;8351.457155284887;8621.698240561676;9436.528173591292;9491.576263560382;10107.545160467796;676.9289224631441;679.5175425447889;708.6315116139579;710.7405796884203;711.5375006245878;4296.419983302277;4311.2239796836875;4616.792244349012;4623.098495793288;4652.418447694038;88268.55865720758;108159;108552;109209;110337;90368.19263614691;93040.53479732694;93255.20234392979;95325.5233723827;95882.52058739802;83276;83470;83587;86021.56989215013;86023.27953441022;7800.414968877243;9068.501232432016;9139.680111196341;9153.405028673114;9182.724518264462;46020;46900;51562.48437515577;54725.72637136948;56975.58146511057;68243.65878170577;69227;71860.28139718746;71924;73847;11072.501737421675;12012.879871201176;12180.939095304468;12855.228686278853;13313.400896959467;14011.649708756955;14905.552833415015;14940.402383904957;14976.326065326868;15017.32422040989;12846.807297890353;13745.312734363042;13785.517506887612;13804.309784510935;13935.233562154179;12943.223406595635;13627.182369057888;13629.522966696119;14030.29848507591;14062.156270623795;12277.570285039981;13386.598402047954;13388.19670819754;13403.396995995668;13531.163783856377;8952.68665596701;9490.572924218288;9572.712818615453;9732.367396119229;9837.213022958289;10679.946600266949;11011.834822477509;11215.71960700989;11302.208845380745;11322.26405283654 05:18:18 INFO - PROCESS | 1631 | __end_tp_report 05:18:18 INFO - PROCESS | 1631 | __start_cc_report 05:18:18 INFO - PROCESS | 1631 | _x_x_mozilla_cycle_collect,2634 05:18:18 INFO - PROCESS | 1631 | __end_cc_report 05:18:18 INFO - PROCESS | 1631 | __startTimestamp1492085898278__endTimestamp 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | ------- Summary: start ------- 05:18:18 INFO - PROCESS | 1631 | Number of tests: 6 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | [#0] dojo.html Cycles:165 Average:5912.73 Median:6141.11 stddev:644.99 (10.5%) stddev-sans-first:613.37 05:18:18 INFO - PROCESS | 1631 | Values: 3293.6 3393.8 3401.4 3421.8 3439.0 5917.8 5986.6 5993.8 6002.7 6032.5 5950.6 5989.7 6018.7 6033.7 6040.1 5370.9 5404.3 5418.4 5432.7 5514.5 5308.1 5308.2 5351.4 5441.3 5481.0 5433.5 5450.2 5457.8 5470.1 5479.9 5472.9 5485.8 5490.6 5493.0 5499.9 5225.0 5229.0 5390.8 5410.5 5435.5 5812.1 5824.3 5831.9 5835.6 5847.1 6241.2 6276.6 6306.8 6325.7 6360.2 6107.8 6345.6 6399.2 6412.9 6425.4 6120.4 6128.2 6132.7 6153.6 6159.2 6222.5 6283.9 6290.8 6569.3 6575.9 6255.9 6261.1 6268.5 6273.7 6275.5 5374.5 6369.9 6378.2 6382.4 6393.9 6285.1 6319.1 6329.7 6335.6 6358.9 6249.1 6315.8 6339.1 6362.2 6365.6 5052.4 5099.9 5100.7 5119.1 5167.3 4591.8 4646.6 4674.8 4687.7 4696.6 5780.9 5795.8 5820.5 5965.8 6061.8 6325.3 6391.6 6411.9 6436.4 6439.9 5378.1 5970.2 6029.6 6040.2 6063.8 5462.3 5489.5 5612.3 5644.5 5647.4 6371.7 6500.0 6542.0 6584.0 6632.8 6417.0 6421.5 6422.3 6428.0 6444.4 5581.6 6580.5 6618.3 6632.7 6656.1 6207.5 6260.4 6288.7 6302.0 6369.6 6223.1 6362.2 6365.7 6382.9 6394.6 6268.4 6296.0 6298.1 6316.5 6338.7 6134.5 6167.9 6226.7 6329.3 6370.8 6097.6 6122.5 6127.4 6127.6 6141.1 5460.2 6191.9 6199.8 6242.1 6319.0 6238.8 6255.5 6287.3 6293.1 6301.7 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | [#1] ext.html Cycles:180 Average:54507.88 Median:17109.86 stddev:111966.31 (654.4%) stddev-sans-first:112207.77 05:18:18 INFO - PROCESS | 1631 | Values: 794.2 813.9 820.7 843.8 882.2 38226.0 39122.8 40702.4 40875.4 40923.8 39183.6 39377.6 39439.6 39451.2 39636.8 7278.6 9006.5 9143.9 9144.0 9157.2 7372.1 10719.4 11006.4 11007.8 11012.0 10965.5 10974.3 10980.9 11005.2 11036.4 7187.6 9778.9 10506.5 10918.9 10944.0 7159.7 10596.7 10873.2 10926.0 10986.6 37858.6 38151.0 38153.8 38168.2 38199.9 3566.1 3585.4 3777.8 3784.4 3842.9 23924.0 24140.6 24423.9 24969.8 25090.4 3045.3 3136.2 3152.3 3161.0 3206.4 326488.0 327921.0 328023.4 329084.0 329188.0 36334.6 37068.4 42860.4 42980.6 43052.0 38273.0 44214.2 44721.4 44978.6 45203.5 12777.9 12829.2 12849.0 12853.8 12860.1 10861.6 10865.3 10877.6 10896.4 10915.3 9662.6 11477.8 11539.7 11605.2 11728.4 5579.5 5617.1 5632.8 5647.9 5677.8 1080.5 1093.3 1098.5 1131.3 1170.3 24167.0 26594.7 26931.0 27431.5 27668.0 1163.2 1165.8 1166.9 1195.7 1212.7 13935.3 13976.7 14195.5 14235.4 14235.6 480777.0 482938.0 483693.0 483875.0 487077.6 55176.4 55575.4 55628.4 55664.4 55773.0 1417.2 1473.0 1473.8 1475.5 1478.9 25034.4 25900.1 26330.1 26379.6 26616.3 40941.6 41037.4 41046.2 41113.8 41247.8 435728.0 436290.0 436377.0 437298.0 437978.0 30354.0 30846.5 30997.5 31054.2 31083.5 42669.9 43519.3 43733.3 43791.0 43840.6 28052.9 29549.9 29571.9 29787.4 30502.1 29562.9 30126.7 30395.5 30462.8 30519.5 35221.8 35832.7 35987.6 35994.3 36217.9 27223.5 29451.1 29901.6 30333.7 30642.0 21020.5 23290.5 23419.2 23495.9 24033.5 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | [#2] jquery.html Cycles:180 Average:42291.70 Median:31758.36 stddev:124023.01 (390.5%) stddev-sans-first:124331.02 05:18:18 INFO - PROCESS | 1631 | Values: 392.1 396.3 417.9 422.1 461.3 12907.7 14702.4 20847.2 21012.0 21648.7 21831.8 22009.6 22317.8 22928.9 23225.9 6438.0 6679.9 6723.2 6787.6 6795.3 6442.4 6640.5 6678.2 6698.9 6730.3 6716.4 6716.5 6717.7 6718.8 6729.3 6691.9 7130.3 7438.4 7634.8 7641.2 7025.7 7242.8 7342.2 7360.9 7378.8 25481.9 25699.5 25910.5 26311.0 26567.2 2697.5 2935.8 3284.0 3377.4 3418.2 14828.0 14933.7 15147.2 15191.7 15209.3 1842.6 1854.9 1893.8 1897.8 1939.0 153254.0 157172.0 162415.4 163030.0 164239.0 19124.0 19444.5 19536.1 19693.6 19729.8 31466.8 31590.4 31739.7 31777.0 31786.5 9465.1 9689.4 9837.3 9949.7 10006.8 8114.1 8123.5 8126.8 8251.3 8288.1 9238.4 9333.4 9467.3 9468.8 9487.0 3492.7 3905.9 4056.8 4092.5 4106.4 881.7 891.9 892.5 910.2 910.8 20228.4 20492.7 20585.9 20866.5 20867.7 709.7 920.6 922.0 939.1 949.0 8717.3 10319.9 10754.5 10836.8 10896.2 729468.0 736292.0 742466.0 749201.0 760653.2 85322.0 135148.0 147504.3 152293.0 157681.0 994.5 1185.4 1211.9 1213.9 1214.6 18220.5 18344.7 19854.0 19974.5 19986.5 62851.0 63349.7 63565.7 63948.0 64015.7 87285.6 88304.0 88534.0 90088.0 90163.2 7816.9 8599.7 9211.8 10727.7 10939.7 10622.7 11852.6 12070.2 12093.9 12163.0 9648.9 10150.1 10213.2 10277.7 10409.9 9713.2 9809.9 9991.0 10047.1 10078.4 10609.3 10737.5 10771.4 10779.0 10827.7 10831.8 10914.5 10941.9 10946.7 11113.2 10192.5 10472.3 10514.3 10518.2 10529.3 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | [#3] mootools.html Cycles:180 Average:6643.67 Median:2863.36 stddev:4154.55 (145.1%) stddev-sans-first:4148.72 05:18:18 INFO - PROCESS | 1631 | Values: 1570.3 1582.4 1605.0 1614.0 1623.9 10507.6 11072.5 11083.8 11093.4 11113.7 10733.8 10853.6 11179.6 11322.0 11335.5 2202.7 2319.0 2404.4 2439.2 2448.5 2326.4 2407.3 2435.1 2437.9 2438.9 2317.7 2376.3 2380.2 2397.3 2404.8 2276.7 2326.2 2329.6 2334.4 2402.6 2389.5 2402.5 2403.1 2417.9 2418.0 9511.6 9563.5 11028.2 11031.4 11053.9 5654.7 5811.9 5822.9 5826.9 5828.3 3544.8 3590.0 3630.3 3653.9 3666.4 1091.6 1197.2 1209.7 1241.5 1264.6 12408.8 13667.6 14016.5 15234.5 15461.0 3968.4 4181.8 4351.0 4355.6 4366.8 10501.2 11093.8 11347.9 11554.7 11558.7 5482.5 5539.9 5547.2 5548.9 5552.4 4975.1 5140.4 5192.0 5234.4 5273.4 3278.2 3819.2 3842.7 3919.8 3927.9 1025.1 1026.1 1041.3 1041.4 1047.5 715.5 789.3 790.7 809.4 827.3 6481.9 6550.6 6628.5 6661.2 6750.5 919.7 972.3 999.7 1005.2 1050.0 4065.4 4160.8 4202.2 4317.1 4323.4 13581.5 14157.0 14172.4 14193.7 14243.4 11661.2 14100.9 14103.2 14104.3 14114.1 8724.8 9765.3 9989.6 10159.8 10470.6 5657.7 6629.3 6736.3 6808.0 6822.7 11899.0 12093.9 12120.8 12132.8 12186.3 12228.8 12425.8 12564.9 12602.1 12624.7 8319.2 8570.7 8570.9 8571.0 8571.9 8941.6 10015.9 10428.6 10545.2 10550.9 7723.0 8650.6 9047.0 9118.0 9405.4 8868.1 9069.8 9104.0 9230.5 9366.5 7641.3 7786.7 7788.2 7801.1 7810.5 5814.6 5828.6 5842.9 6009.2 6046.2 5895.6 5932.8 5944.5 6193.2 6286.7 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | [#4] prototype.html Cycles:180 Average:4549.73 Median:4973.68 stddev:1295.33 (26.0%) stddev-sans-first:1260.61 05:18:18 INFO - PROCESS | 1631 | Values: 381.2 382.1 383.2 391.5 392.7 4854.9 4862.9 5131.8 5158.5 5178.8 4188.7 4393.9 5081.1 5109.0 5123.4 3539.8 3547.3 3979.8 4015.7 4116.3 4051.5 4089.4 4100.0 4123.2 4156.0 4116.9 4129.9 4134.5 4144.2 4158.5 3844.9 3938.8 3960.8 4072.3 4081.9 3621.7 3630.5 3632.3 3636.5 3648.7 4252.6 4496.5 4996.7 5008.5 5035.7 3965.5 4057.3 4321.4 4508.9 4683.1 4807.8 5159.5 5209.7 5314.7 5357.8 3900.0 3993.3 4022.0 4043.2 4059.8 4905.0 4952.8 5623.4 5846.2 5850.4 4317.3 4743.7 4950.4 4960.1 5068.8 4196.6 4407.2 4725.5 4947.2 5092.3 4704.7 5029.0 5247.0 5269.3 5289.9 4655.7 4989.6 5545.1 5593.4 5610.0 1369.1 1437.3 1491.6 1492.4 1590.9 1101.6 1113.1 1140.5 1178.9 1206.6 4427.6 4710.7 5058.1 5241.4 5278.2 5786.4 5801.7 5804.7 5821.8 5841.4 4451.9 4660.8 4796.4 5062.4 5182.7 2641.6 2857.6 2999.0 3002.5 3024.9 4969.8 5290.0 5489.0 5897.9 6003.0 4584.9 5103.8 5151.4 5580.8 5854.1 5453.0 5486.1 5784.9 5890.8 5980.5 4914.1 5334.4 5461.9 5790.9 5880.7 4774.5 4929.7 4999.5 5151.5 5492.0 4977.5 5470.3 5652.3 6072.4 6125.2 3638.9 3739.5 4025.9 4437.4 4621.0 5024.3 5685.0 5732.8 5804.5 5806.9 5212.2 5475.6 5580.9 5618.6 5664.5 4774.9 5082.2 5219.6 5325.5 5470.6 5102.5 5118.3 5377.7 5470.0 5526.7 5407.5 5451.3 5618.0 5677.6 5691.5 4774.7 5187.0 5301.9 5443.0 5542.3 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - PROCESS | 1631 | [#5] yui.html Cycles:180 Average:20646.55 Median:4004.11 stddev:30859.38 (770.7%) stddev-sans-first:30909.44 05:18:18 INFO - PROCESS | 1631 | Values: 656.3 701.4 731.4 775.4 806.2 14213.8 15053.5 15128.2 15141.5 15161.1 13506.2 13695.5 13882.2 13892.7 13912.7 939.5 945.1 963.1 963.6 964.4 949.7 950.7 951.6 953.8 954.0 1011.6 1011.8 1013.7 1013.7 1016.3 1007.2 1009.9 1010.1 1011.8 1011.9 751.7 752.0 752.9 754.3 755.3 12650.2 12847.3 12940.8 13518.9 13760.2 11099.7 11383.4 11598.2 11698.0 12097.8 10311.8 10411.8 10612.5 10619.0 10691.8 3493.1 3539.7 3743.9 3768.9 3775.6 104468.0 109388.3 110880.4 111465.4 113728.0 8715.3 8749.2 8908.2 9021.5 9028.5 16623.8 18502.1 18905.1 18912.6 18962.9 7224.9 7276.1 7525.2 7531.6 7613.8 3659.5 4232.6 4299.9 4300.6 4340.8 3327.5 3423.5 3449.6 3450.7 3451.1 619.4 624.3 638.9 646.6 648.0 655.8 662.2 673.2 683.7 716.3 8351.5 8621.7 9436.5 9491.6 10107.5 676.9 679.5 708.6 710.7 711.5 4296.4 4311.2 4616.8 4623.1 4652.4 88268.6 108159.0 108552.0 109209.0 110337.0 90368.2 93040.5 93255.2 95325.5 95882.5 83276.0 83470.0 83587.0 86021.6 86023.3 7800.4 9068.5 9139.7 9153.4 9182.7 46020.0 46900.0 51562.5 54725.7 56975.6 68243.7 69227.0 71860.3 71924.0 73847.0 11072.5 12012.9 12180.9 12855.2 13313.4 14011.6 14905.6 14940.4 14976.3 15017.3 12846.8 13745.3 13785.5 13804.3 13935.2 12943.2 13627.2 13629.5 14030.3 14062.2 12277.6 13386.6 13388.2 13403.4 13531.2 8952.7 9490.6 9572.7 9732.4 9837.2 10679.9 11011.8 11215.7 11302.2 11322.3 05:18:18 INFO - PROCESS | 1631 | -------- Summary: end -------- 05:18:18 INFO - PROCESS | 1631 | 05:18:18 INFO - TEST-INFO | 1631: exit 0 05:18:18 INFO - TEST-OK | dromaeo_css | took 1120330ms 05:18:18 INFO - TEST-START | kraken 05:18:18 INFO - operating with platform_type : mac_ 05:18:18 INFO - Initialising browser for kraken test... 05:18:18 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmpsZMysH/profile http://localhost:49244/getInfo.html) 05:18:21 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 05:18:21 INFO - PROCESS | 1641 | colorDepth:24 05:18:21 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 05:18:21 INFO - PROCESS | 1641 | __metrics 05:18:21 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:18:21 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:18:22 INFO - TEST-INFO | 1641: exit 0 05:18:22 INFO - Browser initialized. 05:18:22 INFO - Running cycle 1/1 for kraken test... 05:18:22 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/zb/gjztv1j9199774r50d3phz4m00000w/T/tmpsZMysH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:18:34 INFO - PROCESS | 1642 | RSS: Main: 210993152 05:18:34 INFO - PROCESS | 1642 | 05:18:37 INFO - PROCESS | 1642 | 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) 05:18:38 INFO - PROCESS | 1642 | RSS: Main: 264110080 05:18:38 INFO - PROCESS | 1642 | 05:18:40 INFO - PROCESS | 1642 | 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) 05:18:41 INFO - PROCESS | 1642 | RSS: Main: 277467136 05:18:41 INFO - PROCESS | 1642 | 05:18:44 INFO - PROCESS | 1642 | 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) 05:18:45 INFO - PROCESS | 1642 | RSS: Main: 374980608 05:18:45 INFO - PROCESS | 1642 | 05:18:47 INFO - PROCESS | 1642 | 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) 05:18:47 INFO - PROCESS | 1642 | RSS: Main: 276570112 05:18:47 INFO - PROCESS | 1642 | 05:18:49 INFO - PROCESS | 1642 | 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) 05:18:50 INFO - PROCESS | 1642 | RSS: Main: 278040576 05:18:50 INFO - PROCESS | 1642 | 05:18:53 INFO - PROCESS | 1642 | 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) 05:18:54 INFO - PROCESS | 1642 | RSS: Main: 317059072 05:18:54 INFO - PROCESS | 1642 | 05:18:57 INFO - PROCESS | 1642 | 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) 05:18:57 INFO - PROCESS | 1642 | RSS: Main: 318046208 05:18:57 INFO - PROCESS | 1642 | 05:19:00 INFO - PROCESS | 1642 | 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) 05:19:01 INFO - PROCESS | 1642 | RSS: Main: 315781120 05:19:01 INFO - PROCESS | 1642 | 05:19:02 INFO - PROCESS | 1642 | 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) 05:19:03 INFO - PROCESS | 1642 | RSS: Main: 296026112 05:19:03 INFO - PROCESS | 1642 | 05:19:05 INFO - PROCESS | 1642 | 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) 05:19:05 INFO - PROCESS | 1642 | RSS: Main: 299298816 05:19:05 INFO - PROCESS | 1642 | 05:19:08 INFO - PROCESS | 1642 | 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) 05:19:08 INFO - PROCESS | 1642 | RSS: Main: 301596672 05:19:08 INFO - PROCESS | 1642 | 05:19:11 INFO - PROCESS | 1642 | 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) 05:19:12 INFO - PROCESS | 1642 | RSS: Main: 316817408 05:19:12 INFO - PROCESS | 1642 | 05:19:15 INFO - PROCESS | 1642 | 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) 05:19:15 INFO - PROCESS | 1642 | RSS: Main: 309743616 05:19:15 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | 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) 05:19:17 INFO - PROCESS | 1642 | RSS: Main: 354500608 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | __start_tp_report 05:19:17 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 05:19:17 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 05:19:17 INFO - PROCESS | 1642 | |i|pagename|runs| 05:19:17 INFO - PROCESS | 1642 | |0;ai-astar;106;118;117;119;118;117;117;117;118;118 05:19:17 INFO - PROCESS | 1642 | |1;audio-beat-detection;153;111;130;165;109;110;114;110;128;109 05:19:17 INFO - PROCESS | 1642 | |2;audio-dft;185;185;184;186;183;187;184;186;185;186 05:19:17 INFO - PROCESS | 1642 | |3;audio-fft;82;62;61;102;88;87;83;60;76;61 05:19:17 INFO - PROCESS | 1642 | |4;audio-oscillator;84;70;87;67;69;68;81;69;69;83 05:19:17 INFO - PROCESS | 1642 | |5;imaging-gaussian-blur;89;88;88;88;88;88;89;89;87;87 05:19:17 INFO - PROCESS | 1642 | |6;imaging-darkroom;95;94;94;98;94;95;94;98;95;94 05:19:17 INFO - PROCESS | 1642 | |7;imaging-desaturate;83;85;82;93;84;87;84;86;83;84 05:19:17 INFO - PROCESS | 1642 | |8;json-parse-financial;62;63;61;61;64;64;63;64;62;63 05:19:17 INFO - PROCESS | 1642 | |9;json-stringify-tinderbox;54;64;68;62;58;57;57;56;57;57 05:19:17 INFO - PROCESS | 1642 | |10;stanford-crypto-aes;83;83;82;98;90;105;104;85;85;88 05:19:17 INFO - PROCESS | 1642 | |11;stanford-crypto-ccm;131;140;136;132;136;144;150;144;181;139 05:19:17 INFO - PROCESS | 1642 | |12;stanford-crypto-pbkdf2;164;164;164;167;164;166;156;165;168;163 05:19:17 INFO - PROCESS | 1642 | |13;stanford-crypto-sha256-iterative;66;66;64;66;67;65;65;64;77;82 05:19:17 INFO - PROCESS | 1642 | __end_tp_report 05:19:17 INFO - PROCESS | 1642 | __start_cc_report 05:19:17 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,2153 05:19:17 INFO - PROCESS | 1642 | __end_cc_report 05:19:17 INFO - PROCESS | 1642 | __startTimestamp1492085957814__endTimestamp 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | ------- Summary: start ------- 05:19:17 INFO - PROCESS | 1642 | Number of tests: 14 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#0] ai-astar Cycles:10 Average:116.50 Median:118.00 stddev:3.75 (3.2%) stddev-sans-first:0.71 05:19:17 INFO - PROCESS | 1642 | Values: 106.0 118.0 117.0 119.0 118.0 117.0 117.0 117.0 118.0 118.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:121.00 stddev:20.23 (16.7%) stddev-sans-first:18.52 05:19:17 INFO - PROCESS | 1642 | Values: 153.0 111.0 130.0 165.0 109.0 110.0 114.0 110.0 128.0 109.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.50 stddev:1.20 (0.6%) stddev-sans-first:1.27 05:19:17 INFO - PROCESS | 1642 | Values: 185.0 185.0 184.0 186.0 183.0 187.0 184.0 186.0 185.0 186.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#3] audio-fft Cycles:10 Average:76.20 Median:79.00 stddev:14.63 (18.5%) stddev-sans-first:15.37 05:19:17 INFO - PROCESS | 1642 | Values: 82.0 62.0 61.0 102.0 88.0 87.0 83.0 60.0 76.0 61.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:75.50 stddev:7.96 (10.5%) stddev-sans-first:7.70 05:19:17 INFO - PROCESS | 1642 | Values: 84.0 70.0 87.0 67.0 69.0 68.0 81.0 69.0 69.0 83.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:88.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 05:19:17 INFO - PROCESS | 1642 | Values: 89.0 88.0 88.0 88.0 88.0 88.0 89.0 89.0 87.0 87.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:1.60 (1.7%) stddev-sans-first:1.69 05:19:17 INFO - PROCESS | 1642 | Values: 95.0 94.0 94.0 98.0 94.0 95.0 94.0 98.0 95.0 94.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:84.50 stddev:3.14 (3.7%) stddev-sans-first:3.24 05:19:17 INFO - PROCESS | 1642 | Values: 83.0 85.0 82.0 93.0 84.0 87.0 84.0 86.0 83.0 84.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:1.16 (1.8%) stddev-sans-first:1.20 05:19:17 INFO - PROCESS | 1642 | Values: 62.0 63.0 61.0 61.0 64.0 64.0 63.0 64.0 62.0 63.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:57.50 stddev:4.29 (7.5%) stddev-sans-first:4.16 05:19:17 INFO - PROCESS | 1642 | Values: 54.0 64.0 68.0 62.0 58.0 57.0 57.0 56.0 57.0 57.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#10] stanford-crypto-aes Cycles:10 Average:90.30 Median:85.00 stddev:8.82 (10.4%) stddev-sans-first:8.95 05:19:17 INFO - PROCESS | 1642 | Values: 83.0 83.0 82.0 98.0 90.0 105.0 104.0 85.0 85.0 88.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:142.00 stddev:14.46 (10.2%) stddev-sans-first:14.64 05:19:17 INFO - PROCESS | 1642 | Values: 131.0 140.0 136.0 132.0 136.0 144.0 150.0 144.0 181.0 139.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.10 Median:164.50 stddev:3.25 (2.0%) stddev-sans-first:3.44 05:19:17 INFO - PROCESS | 1642 | Values: 164.0 164.0 164.0 167.0 164.0 166.0 156.0 165.0 168.0 163.0 05:19:17 INFO - PROCESS | 1642 | 05:19:17 INFO - PROCESS | 1642 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:66.00 stddev:6.14 (9.3%) stddev-sans-first:6.46 05:19:17 INFO - PROCESS | 1642 | Values: 66.0 66.0 64.0 66.0 67.0 65.0 65.0 64.0 77.0 82.0 05:19:17 INFO - PROCESS | 1642 | -------- Summary: end -------- 05:19:17 INFO - PROCESS | 1642 | 05:19:18 INFO - TEST-INFO | 1642: exit 0 05:19:18 INFO - TEST-OK | kraken | took 59776ms 05:19:18 INFO - SUITE-END | took 1180s 05:19:18 INFO - Completed test suite (00:19:40) 05:19:18 INFO - javascript benchmark 05:19:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7552.461972234525, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3293.604767427909, 3393.813340266285, 3401.40475416802, 3421.7946923184627, 3439.0, 5917.763289468421, 5986.551008674356, 5993.760249590011, 6002.699865006754, 6032.547558933075, 5950.5834591578605, 5989.730462129204, 6018.729157187926, 6033.7284822183, 6040.063790112539, 5370.946290537094, 5404.270423492837, 5418.431064738204, 5432.67403955762, 5514.476124768141, 5308.071087559681, 5308.203769434577, 5351.357837059559, 5441.319835020622, 5481.040817856859, 5433.510984011438, 5450.236966824647, 5457.781688732446, 5470.124780035195, 5479.917801232985, 5472.863178420532, 5485.807996720096, 5490.56075513958, 5493.011257973563, 5499.945000550003, 5224.981128679909, 5229.032628963616, 5390.838274851761, 5410.513053825174, 5435.510804027617, 5812.070068789016, 5824.30108386992, 5831.854203644901, 5835.649861008353, 5847.122931560258, 6241.157443745082, 6276.560640755149, 6306.779262725829, 6325.683715814191, 6360.173177486943, 6107.84730381744, 6345.619262844244, 6399.232092148972, 6412.871742565123, 6425.421712045892, 6120.357362476965, 6128.233970753611, 6132.6933653317155, 6153.569250152491, 6159.168512250879, 6222.471089957314, 6283.937160628335, 6290.842728931813, 6569.343065693488, 6575.934240657532, 6255.937440625627, 6261.092141639528, 6268.4671802897265, 6273.686315684197, 6275.497960163176, 5374.54316383104, 6369.904451433232, 6378.202724659418, 6382.425581697669, 6393.904091438632, 6285.120083188357, 6319.146915166486, 6329.746810127635, 6335.556511044183, 6358.904616430757, 6249.0938813871735, 6315.810525684236, 6339.112524246517, 6362.172917520693, 6365.649889256136, 5052.368453943257, 5099.898002039939, 5100.744962751922, 5119.10415677255, 5167.250748641502, 4591.77041147948, 4646.628269738413, 4674.766261686902, 4687.718736875799, 4696.577308042292, 5780.913286300709, 5795.82612521625, 5820.534357251409, 5965.791197308074, 6061.818145455643, 6325.272593651789, 6391.616503009741, 6411.942029565163, 6436.388543088399, 6439.9356006440275, 5378.139497680352, 5970.194023806731, 6029.638221706711, 6040.244969378828, 6063.787767428119, 5462.289902312754, 5489.451054894478, 5612.298462692163, 5644.491995720405, 5647.350554686263, 6371.681415929185, 6499.967500162469, 6541.96729016371, 6583.967080164569, 6632.801015969529, 6417.0, 6421.486281097407, 6422.325655806073, 6428.035794630843, 6444.355564443518, 5581.609287349846, 6580.473562114924, 6618.305077966744, 6632.734690612321, 6656.068150459037, 6207.503399728102, 6260.373962603703, 6288.6855657217875, 6301.96849015752, 6369.585976911486, 6223.128761973418, 6362.172917520693, 6365.745370185326, 6382.872342553216, 6394.584352017104, 6268.435840774352, 6295.96852015737, 6298.08677741734, 6316.494680425647, 6338.714757835816, 6134.467201660849, 6167.938320616916, 6226.688665566613, 6329.335419780857, 6370.8088757337355, 6097.573090410549, 6122.479589234965, 6127.417895299939, 6127.571070025056, 6141.109539116714, 5460.208269800936, 6191.938080619136, 6199.783007594712, 6242.094896240111, 6318.968405157945, 6238.844028899132, 6255.46828519581, 6287.2769631492965, 6293.118963345227, 6301.747930082745], "value": 5871.890640738974, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.1700922535949, 813.8982627171605, 820.7414664380716, 843.7848348671088, 882.1927935938617, 38226.04434889126, 39122.80438597807, 40702.38946415803, 40875.386869196984, 40923.79538102313, 39183.608163918354, 39377.60622393775, 39439.60560394395, 39451.210975780465, 39636.801815990955, 7278.563286202845, 9006.504642244674, 9143.862842057375, 9144.039875813043, 9157.175854173123, 7372.115346158468, 10719.410432426213, 11006.394648294341, 11007.834882476769, 11012.008919197271, 10965.50655220513, 10974.286671366375, 10980.890191098106, 11005.229633925628, 11036.392998385125, 7187.604681742502, 9778.853317200177, 10506.474676266233, 10918.945405272963, 10943.99999999996, 7159.67781449836, 10596.682099536949, 10873.238873278873, 10926.0, 10986.560537578487, 37858.62141378578, 38151.04622384435, 38153.809230953666, 38168.23663526714, 38199.854004379915, 3566.1084728817796, 3585.354636165489, 3777.7922214278206, 3784.375578029622, 3842.9231415371537, 23924.0, 24140.63789043166, 24423.87788061066, 24969.75151242449, 25090.37274068163, 3045.299581096338, 3136.184592006072, 3152.3064925716308, 3160.952585711216, 3206.3907857506997, 326488.0, 327921.0, 328023.35988319904, 329083.9999999976, 329188.0, 36334.63665363366, 37068.44397334042, 42860.35709464361, 42980.57019429766, 43052.0, 38273.0, 44214.23143074306, 44721.4347497836, 44978.55021449744, 45203.54796452059, 12777.872221277668, 12829.23024618526, 12849.0, 12853.807192892114, 12860.099793014399, 10861.565537378416, 10865.348079115278, 10877.564897404174, 10896.40069796153, 10915.345079295268, 9662.56518456671, 11477.827832582518, 11539.711507212387, 11605.245659032136, 11728.413579321, 5579.497845193952, 5617.129344951457, 5632.774689012475, 5647.943520564824, 5677.829665110054, 1080.5353697909975, 1093.2893619147617, 1098.549594666199, 1131.3494740524231, 1170.280277629252, 24167.033318667407, 26594.734052659227, 26931.0, 27431.451370972467, 27668.0, 1163.2206421697483, 1165.755558421724, 1166.8658104317942, 1195.6950977500683, 1212.739261058859, 13935.303234838217, 13976.720465590835, 14195.503157389649, 14235.359408826627, 14235.572932812032, 480777.0, 482938.0, 483693.0, 483875.0, 487077.5646121747, 55176.448235517935, 55575.444245557024, 55628.44371556232, 55664.44335556593, 55773.0, 1417.185118556834, 1473.035161968912, 1473.7936688863354, 1475.5057055886184, 1478.8964772465824, 25034.37414064663, 25900.093496727524, 26330.078447254255, 26379.60430593581, 26616.334591635365, 40941.59058409497, 41037.38443923284, 41046.1790764189, 41113.79443102765, 41247.762567123034, 435728.0, 436290.0, 436377.0, 437298.0, 437978.0, 30354.0, 30846.53730194004, 30997.53503697401, 31054.22364440907, 31083.533746994264, 42669.933251668954, 43519.34720979251, 43733.34399983939, 43791.0, 43840.561594384926, 28052.85973570119, 29549.85225073947, 29571.85214073916, 29787.404251915275, 30502.084937451913, 29562.852185738935, 30126.69873301239, 30395.54406683946, 30462.84768576143, 30519.542206867358, 35221.82389088038, 35832.74585389499, 35987.64012359948, 35994.28011439704, 36217.91346259617, 27223.455530889667, 29451.11646650604, 29901.551476728302, 30333.696663033086, 30642.0, 21020.474488137308, 23290.53418931578, 23419.180328688413, 23495.88252058729, 24033.519329612962], "value": 18099.700291168116, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.1196912930468, 396.27877263380645, 417.8955261184704, 422.0882892951225, 461.287311104344, 12907.677308067303, 14702.41190352386, 20847.166113355484, 21012.0, 21648.675269870924, 21831.781682183173, 22009.559808803813, 22317.77682223177, 22928.885355573242, 23225.883870580623, 6437.9678101609425, 6679.899801502981, 6723.159605049369, 6787.592744435325, 6795.286494918037, 6442.420182183602, 6640.535162538624, 6678.165229346332, 6698.8995165072565, 6730.293319201487, 6716.395524402803, 6716.46268298535, 6717.6977036033495, 6718.83202919926, 6729.2597814240635, 6691.933080669204, 7130.286971302906, 7438.367738742213, 7634.770956871303, 7641.23587641237, 7025.718971241145, 7242.782716518513, 7342.229065948105, 7360.852782944311, 7378.815529611803, 25481.853316600613, 25699.48601027969, 25910.481790364087, 26311.0, 26567.202983910513, 2697.4874773792917, 2935.7504612107996, 3284.0, 3377.4258376076127, 3418.2479854431986, 14828.0, 14933.70132597342, 15147.166905820175, 15191.696166076727, 15209.315580798891, 1842.5762074722888, 1854.8701590888643, 1893.810618938109, 1897.766140169393, 1938.9709154362695, 153254.0, 157171.99999999886, 162415.3758462412, 163030.0, 164239.0, 19124.0, 19444.458279583636, 19536.12087456068, 19693.606127877363, 19729.77161052322, 31466.842665786524, 31590.368192636477, 31739.68260317367, 31777.046688598915, 31786.523202151988, 9465.100815422662, 9689.41863488193, 9837.311388202757, 9949.651762188434, 10006.849897251546, 8114.148014458515, 8123.512589244546, 8126.837463250702, 8251.29863961558, 8288.088310285864, 9238.399504032217, 9333.439993600407, 9467.337286389886, 9468.810623787485, 9486.95256523713, 3492.738044646611, 3905.8776806989786, 4056.817443215062, 4092.5062352241216, 4106.445629839993, 881.6649673124172, 891.9286457083417, 892.4600616626977, 910.1944778870615, 910.7540963939832, 20228.393148205578, 20492.69260961087, 20585.89707051455, 20866.47833804167, 20867.686984695243, 709.726755199252, 920.5535315372039, 921.9861702074475, 939.1359948847077, 948.9525523723786, 8717.302615790848, 10319.948400258101, 10754.462276886125, 10836.837447438136, 10896.237263391646, 729468.0, 736292.0, 742466.0, 749201.0, 760653.1967340128, 85322.0, 135148.0, 147504.26247868905, 152293.0, 157681.0, 994.472929347447, 1185.3658292813475, 1211.9212251203644, 1213.9028877689764, 1214.6356093172012, 18220.45338639843, 18344.72482912784, 19854.0, 19974.50063748418, 19986.500337491678, 62851.0, 63349.68325158345, 63565.68217158884, 63948.0, 64015.679921600095, 87285.56357218174, 88304.0, 88534.0, 90088.0, 90163.19673606622, 7816.9447124637445, 8599.656013759379, 9211.815763684823, 10727.731806704895, 10939.671809845717, 10622.681319560426, 11852.644420667393, 12070.249813777129, 12093.879061209274, 12163.0, 9648.903510964801, 10150.137238334824, 10213.234007449322, 10277.743056423351, 10409.9479502602, 9713.174380177765, 9809.852852207365, 9990.950045250018, 10047.145992590711, 10078.395296282246, 10609.257351985596, 10737.516811743646, 10771.353718776898, 10779.02988731018, 10827.67516974492, 10831.783364332512, 10914.454277286262, 10941.945290273816, 10946.715985204248, 11113.22207445471, 10192.541335640062, 10472.319299245524, 10514.316569422963, 10518.211134165122, 10529.26295159362], "value": 11177.722829434946, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1570.2619768708703, 1582.4461438496528, 1604.9518514444565, 1614.0396464103853, 1623.8700903927686, 10507.579696812129, 11072.501737421815, 11083.833742493869, 11093.445327733602, 11113.666590002304, 10733.83899241512, 10853.565857365717, 11179.552817887296, 11322.0, 11335.489902954367, 2202.744435671668, 2319.026009076192, 2404.398900274936, 2439.2438344113293, 2448.4980578981267, 2326.3951372643064, 2407.313915534073, 2435.1355268879556, 2437.878106094688, 2438.85366877987, 2317.733460652021, 2376.275236053003, 2380.1788383007834, 2397.2688330059327, 2404.843685160459, 2276.6926464927355, 2326.162581470669, 2329.5923213437663, 2334.4280651240556, 2402.6396040593877, 2389.522095580891, 2402.4594466244953, 2403.062805505854, 2417.867017314047, 2417.987910060456, 9511.57197926095, 9563.474008929506, 11028.172887033505, 11031.393273369962, 11053.889461105366, 5654.660720356792, 5811.941880581182, 5822.912656310159, 5826.883462330729, 5828.329742079631, 3544.7518673692853, 3590.0486371111674, 3630.3283892479976, 3653.8721144759807, 3666.4133738601963, 1091.5851976249055, 1197.2038594334756, 1209.7278112424722, 1241.5096037065398, 1264.6332563556557, 12408.821161989597, 13667.589972300846, 14016.509422170277, 15234.466793662279, 15460.995035322669, 3968.4245784361106, 4181.832726690959, 4350.978245108754, 4355.562664320766, 4366.803493842721, 10501.21240906935, 11093.83359249612, 11347.943260283811, 11554.653360399201, 11558.711032224262, 5482.533984611273, 5539.861503462445, 5547.195656629766, 5548.861278468071, 5552.444755524496, 4975.0796102721115, 5140.434552199255, 5192.0, 5234.3718753749035, 5273.419923808378, 3278.213228825065, 3819.236152769457, 3842.654161125512, 3919.8240527841535, 3927.9018024549046, 1025.1388833379997, 1026.1431704526653, 1041.317936751429, 1041.3595638682307, 1047.4553232319151, 715.4955756191872, 789.3290702902601, 790.7430085222326, 809.4091313341294, 827.2678679368802, 6481.902771458432, 6550.6069635822005, 6628.502862285348, 6661.233958094881, 6750.493712971547, 919.7102912582515, 972.3388096094582, 999.6601155607128, 1005.2360206243162, 1049.9790004199874, 4065.4308396824467, 4160.771157586301, 4202.222588821078, 4317.11499142675, 4323.351497275371, 13581.524646637321, 14157.00900936945, 14172.362243699059, 14193.716125677429, 14243.430262789372, 11661.183717139718, 14100.85899141016, 14103.224322662147, 14104.294785260696, 14114.082584631966, 8724.781880452912, 9765.267604929659, 9989.550470228713, 10159.796804063877, 10470.638816953748, 5657.745401456862, 6629.337066293525, 6736.326367363225, 6808.0, 6822.658867056726, 11899.048076153715, 12093.939530302292, 12120.75758484843, 12132.818007729715, 12186.268823870596, 12228.816567751668, 12425.751484970431, 12564.87435125637, 12602.054845886778, 12624.74750505003, 8319.168083191633, 8570.700025499327, 8570.871436928575, 8570.957145214483, 8571.914280857112, 8941.642334306554, 10015.949920250352, 10428.582856685647, 10545.156387488829, 10550.89449105499, 7722.961385193263, 8650.56747162652, 9047.004829468622, 9117.954410227907, 9405.43567385959, 8868.068852770624, 9069.77325566866, 9103.954480227821, 9230.538473076454, 9366.531673416166, 7641.2740789624895, 7786.727464538941, 7788.182240864628, 7801.063872335129, 7810.453268271166, 5814.5639077068545, 5828.562857785768, 5842.853928651818, 6009.158717779602, 6046.2139921809885, 5895.557833162444, 5932.849490578921, 5944.464998150187, 6193.225846769154, 6286.654234017173], "value": 5060.732408549289, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.24513463342583, 382.09824813440287, 383.1972018620988, 391.4715134568331, 392.68585131894486, 4854.931914978708, 4862.902741945159, 5131.794728210872, 5158.529819001586, 5178.824893544711, 4188.680565621827, 4393.912121757571, 5081.129162023454, 5108.974455127728, 5123.359580052494, 3539.752217344787, 3547.290541891619, 3979.801009949491, 4015.687058177001, 4116.34138537834, 4051.4733084699096, 4089.407035979781, 4099.995501102234, 4123.237201117803, 4156.0, 4116.8970775730695, 4129.9174016519655, 4134.4625198724125, 4144.23331683638, 4158.480189976253, 3844.9042023023585, 3938.763674179544, 3960.782156981365, 4072.3077076897, 4081.8571350002903, 3621.692156166729, 3630.528031355907, 3632.2928223735066, 3636.4727114568504, 3648.7081033517256, 4252.617264446215, 4496.5278645742055, 4996.675216110941, 5008.524190201925, 5035.672681275705, 3965.543962444327, 4057.2899742544937, 4321.437484402296, 4508.887277818048, 4683.063387322515, 4807.8124453373985, 5159.477954003578, 5209.7134657593815, 5314.707691076989, 5357.7589008494715, 3899.972014552417, 3993.3061917725986, 4022.0, 4043.2317859606583, 4059.776712280823, 4905.043516514281, 4952.796005777466, 5623.409541998113, 5846.239988801472, 5850.3564607893095, 4317.294668605883, 4743.651892313327, 4950.381202349707, 4960.098342469696, 5068.797248110034, 4196.5803419658405, 4407.228734971432, 4725.47187254817, 4947.180107338782, 5092.26162206478, 4704.741239231875, 5028.974855125774, 5247.0, 5269.288646032813, 5289.947100528945, 4655.716740598657, 4989.553029621442, 5545.140503221926, 5593.440655934374, 5610.0, 1369.0621923982103, 1437.324457504971, 1491.6196369925817, 1492.3881208704597, 1590.8647764939878, 1101.6144349477618, 1113.0872684398878, 1140.5002421815286, 1178.8585369755683, 1206.5897594817804, 4427.5572442755465, 4710.69380490267, 5058.079988009322, 5241.449647786927, 5278.155495120763, 5786.42135786418, 5801.7099145042575, 5804.65172089676, 5821.76712931478, 5841.386654401312, 4451.933221001687, 4660.7669616519715, 4796.37647105874, 5062.367204099488, 5182.714950677749, 2641.556560181824, 2857.6142220799925, 2998.995336562276, 3002.489576771954, 3024.909252722422, 4969.82534670871, 5290.0, 5488.9725551372785, 5897.882042359129, 6002.969985150134, 4584.899624090194, 5103.821366252238, 5151.4333423323405, 5580.832575022756, 5854.058130997041, 5453.0, 5486.067368547357, 5784.913226301693, 5890.793822216202, 5980.461758441674, 4914.115459217375, 5334.439883812144, 5461.909970383073, 5790.94209057904, 5880.676562788916, 4774.474807771212, 4929.679570827885, 4999.500049994971, 5151.5106064923775, 5491.956528259618, 4977.527134922177, 5470.261514695465, 5652.293463317085, 6072.449571180203, 6125.151804410971, 3638.890833275006, 3739.45777862203, 4025.8993525162105, 4437.378766972691, 4621.0, 5024.321716568216, 5685.03866166172, 5732.828015159552, 5804.506616937608, 5806.941930580809, 5212.244224587489, 5475.561955043667, 5580.944190558205, 5618.606697531297, 5664.546836253007, 4774.904501909873, 5082.186850103965, 5219.634625576173, 5325.467453254799, 5470.64440811346, 5102.4642412546145, 5118.309028281284, 5377.677339359651, 5469.972650136724, 5526.696031718294, 5407.510597821966, 5451.337342110694, 5617.9719101404235, 5677.6373670318, 5691.544676425959, 4774.737389443614, 5187.031920580835, 5301.946980530145, 5443.013300145498, 5542.334919809648], "value": 4176.247189437758, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.297761395307, 701.3792793377859, 731.4404480572363, 775.3990657240641, 806.1656185847648, 14213.786793198098, 15053.473128440506, 15128.243587820592, 15141.545753627395, 15161.090334579932, 13506.189628622265, 13695.452181912711, 13882.236476993761, 13892.74965253127, 13912.652183695413, 939.544321004314, 945.1115951006067, 963.1331801378745, 963.5712108111893, 964.3827950111898, 949.710338346804, 950.7005293332578, 951.6050838901847, 953.7806304549957, 954.0268925695786, 1011.6054738651932, 1011.8178727828988, 1013.7262939006464, 1013.7465633591602, 1016.2987538598344, 1007.1841808135433, 1009.8990100989915, 1010.1111022300329, 1011.812814629296, 1011.9443430611313, 751.6655088485626, 752.0073502976073, 752.8833030880222, 754.3324158120048, 755.3390783064818, 12650.177738446971, 12847.29339886306, 12940.805887911689, 13518.932405338008, 13760.247950409857, 11099.667009989713, 11383.430828458544, 11598.188126831124, 11698.0, 12097.758044839054, 10311.84532232017, 10411.791764164751, 10612.522436490377, 10619.0, 10691.83962240567, 3493.1441796759705, 3539.663731945461, 3743.8689645862537, 3768.8680896168503, 3775.6035616260174, 104467.95532044658, 109388.26529336751, 110880.4455977723, 111465.44267278693, 113728.0, 8715.346349023848, 8749.180881272398, 8908.198262156437, 9021.50381728998, 9028.458292502472, 16623.750643740354, 18502.074896255133, 18905.14926828296, 18912.621747564972, 18962.905185473985, 7224.927750722531, 7276.100792051507, 7525.2098529654695, 7531.623418829036, 7613.847723045508, 3659.524261845943, 4232.619064284229, 4299.871003869889, 4300.569943005675, 4340.804663790137, 3327.5175099610906, 3423.4864770284157, 3449.586049674055, 3450.741194410379, 3451.137215696076, 619.4332186049804, 624.2664868779166, 638.9265234497999, 646.5603389695052, 647.9740810367626, 655.8360409897525, 662.1656712542232, 673.2291526202471, 683.7128406069463, 716.2658275267892, 8351.457155284887, 8621.698240561676, 9436.528173591292, 9491.576263560382, 10107.545160467796, 676.9289224631441, 679.5175425447889, 708.6315116139579, 710.7405796884203, 711.5375006245878, 4296.419983302277, 4311.2239796836875, 4616.792244349012, 4623.098495793288, 4652.418447694038, 88268.55865720758, 108159.0, 108552.0, 109209.0, 110337.0, 90368.19263614691, 93040.53479732694, 93255.20234392979, 95325.5233723827, 95882.52058739802, 83276.0, 83470.0, 83587.0, 86021.56989215013, 86023.27953441022, 7800.414968877243, 9068.501232432016, 9139.680111196341, 9153.405028673114, 9182.724518264462, 46020.0, 46900.0, 51562.48437515577, 54725.72637136948, 56975.58146511057, 68243.65878170577, 69227.0, 71860.28139718746, 71924.0, 73847.0, 11072.501737421675, 12012.879871201176, 12180.939095304468, 12855.228686278853, 13313.400896959467, 14011.649708756955, 14905.552833415015, 14940.402383904957, 14976.326065326868, 15017.32422040989, 12846.807297890353, 13745.312734363042, 13785.517506887612, 13804.309784510935, 13935.233562154179, 12943.223406595635, 13627.182369057888, 13629.522966696119, 14030.29848507591, 14062.156270623795, 12277.570285039981, 13386.598402047954, 13388.19670819754, 13403.396995995668, 13531.163783856377, 8952.68665596701, 9490.572924218288, 9572.712818615453, 9732.367396119229, 9837.213022958289, 10679.946600266949, 11011.834822477509, 11215.71960700989, 11302.208845380745, 11322.26405283654], "value": 7390.752985718036, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1432.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 117.0, 119.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 111.0, 130.0, 165.0, 109.0, 110.0, 114.0, 110.0, 128.0, 109.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 184.0, 186.0, 183.0, 187.0, 184.0, 186.0, 185.0, 186.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 61.0, 102.0, 88.0, 87.0, 83.0, 60.0, 76.0, 61.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 87.0, 67.0, 69.0, 68.0, 81.0, 69.0, 69.0, 83.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 88.0, 88.0, 88.0, 89.0, 89.0, 87.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 98.0, 94.0, 95.0, 94.0, 98.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 82.0, 93.0, 84.0, 87.0, 84.0, 86.0, 83.0, 84.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 61.0, 61.0, 64.0, 64.0, 63.0, 64.0, 62.0, 63.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 64.0, 68.0, 62.0, 58.0, 57.0, 57.0, 56.0, 57.0, 57.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 98.0, 90.0, 105.0, 104.0, 85.0, 85.0, 88.0], "value": 90.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 140.0, 136.0, 132.0, 136.0, 144.0, 150.0, 144.0, 181.0, 139.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 164.0, 167.0, 164.0, 166.0, 156.0, 165.0, 168.0, 163.0], "value": 164.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 66.0, 67.0, 65.0, 65.0, 64.0, 77.0, 82.0], "value": 68.2, "unit": "score"}]}]} 05:19:19 INFO - Return code: 0 05:19:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:19:19 ERROR - # TBPL SUCCESS # 05:19:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:19:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:19:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:19:19 INFO - 2017-04-13 05:19:19.133 screenresolution[1646:9842] starting screenresolution argv=screenresolution get 05:19:19 INFO - 2017-04-13 05:19:19.136 screenresolution[1646:9842] Display 0: 1600x1200x32@60 05:19:19 INFO - 2017-04-13 05:19:19.142 screenresolution[1647:9845] starting screenresolution argv=screenresolution list 05:19:19 INFO - Available Modes on Display 0 05:19:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:19:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:19:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:19:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:19:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:19:19 INFO - Intel Iris: 05:19:19 INFO - Chipset Model: Intel Iris 05:19:19 INFO - Type: GPU 05:19:19 INFO - Bus: Built-In 05:19:19 INFO - VRAM (Dynamic, Max): 1536 MB 05:19:19 INFO - Vendor: Intel (0x8086) 05:19:19 INFO - Device ID: 0x0a2e 05:19:19 INFO - Revision ID: 0x0009 05:19:19 INFO - Displays: 05:19:19 INFO - Display: 05:19:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:19:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:19:19 INFO - Display Serial Number: 1600x1200 60 05:19:19 INFO - Main Display: Yes 05:19:19 INFO - Mirror: Off 05:19:19 INFO - Online: Yes 05:19:19 INFO - Rotation: Supported 05:19:19 INFO - Return code: 0 05:19:19 INFO - Running post-action listener: _resource_record_post_action 05:19:19 INFO - [mozharness: 2017-04-13 12:19:19.378807Z] Finished run-tests step (success) 05:19:19 INFO - Running post-run listener: _resource_record_post_run 05:19:19 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 38843392; Write bytes: 510161920; Read time: 417; Write time: 1707 05:19:19 INFO - TinderboxPrint: CPU usage
53.9% 05:19:19 INFO - TinderboxPrint: I/O read bytes / time
38,843,392 / 417 05:19:19 INFO - TinderboxPrint: I/O write bytes / time
510,161,920 / 1,707 05:19:19 INFO - TinderboxPrint: CPU idle
2,213.9 (46.1%) 05:19:19 INFO - TinderboxPrint: CPU system
958.0 (19.9%) 05:19:19 INFO - TinderboxPrint: CPU user
1,633.8 (34.0%) 05:19:19 INFO - TinderboxPrint: Swap in / out
246,837,248 / 0 05:19:19 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203917824; Write bytes: 202106880; Read time: 15501; Write time: 477 05:19:19 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36779008; Write bytes: 297053184; Read time: 357; Write time: 1194 05:19:19 INFO - Running post-run listener: _upload_blobber_files 05:19:19 INFO - Blob upload gear active. 05:19:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:19:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:19:20 INFO - (blobuploader) - INFO - Open directory for files ... 05:19:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:19:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:19:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:19:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:19:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:19:21 INFO - (blobuploader) - INFO - Done attempting. 05:19:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:19:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:19:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:19:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:19:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:19:22 INFO - (blobuploader) - INFO - Done attempting. 05:19:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:19:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:19:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:19:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:19:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:19:23 INFO - (blobuploader) - INFO - Done attempting. 05:19:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:19:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:19:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:19:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:19:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:19:25 INFO - (blobuploader) - INFO - Done attempting. 05:19:25 INFO - (blobuploader) - INFO - Iteration through files over. 05:19:25 INFO - Return code: 0 05:19:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:19:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:19:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72110e42d1e86d3a0192690ac986572eea86a19eea688a11337ab1c126a55f3b6b655651d1b34891684f688ec561066c504565c80b33499633d968ba26e3cd02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326e5c9644632a370f9b806d23f33fc961b95ae78f7d60067af6ddd10f98faf5224711c007faf543d7508da8bc45d861f3fb4630b0d5a2175fbec7adfad14783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f30ff90c9753239cd0f170ccccb73495e74a222338e9444d122c66e1d4748e5874018dc686dcb7323ee7ef81be38e84f23eefd9408c873c082016a6b56b8653", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7560c7492950cbb8976dec7232abb4a9174ac4f72cd8424001f0dccef0b1d179ad6dc6f261f1023a35afe7b6e62ff6644aac07d45abffbd152f9519913672a1"} 05:19:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:19:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:19:25 INFO - Contents: 05:19:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/72110e42d1e86d3a0192690ac986572eea86a19eea688a11337ab1c126a55f3b6b655651d1b34891684f688ec561066c504565c80b33499633d968ba26e3cd02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326e5c9644632a370f9b806d23f33fc961b95ae78f7d60067af6ddd10f98faf5224711c007faf543d7508da8bc45d861f3fb4630b0d5a2175fbec7adfad14783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f30ff90c9753239cd0f170ccccb73495e74a222338e9444d122c66e1d4748e5874018dc686dcb7323ee7ef81be38e84f23eefd9408c873c082016a6b56b8653", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7560c7492950cbb8976dec7232abb4a9174ac4f72cd8424001f0dccef0b1d179ad6dc6f261f1023a35afe7b6e62ff6644aac07d45abffbd152f9519913672a1"} 05:19:25 INFO - Running post-run listener: copy_logs_to_upload_dir 05:19:25 INFO - Copying logs to upload dir... 05:19:25 INFO - mkdir: /builds/slave/test/build/upload/logs 05:19:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.995387 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-13 05:19:25.495352) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 05:19:25.498557) ========= 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.HaJHQwIBRX/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.jaDA9n93dD/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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/72110e42d1e86d3a0192690ac986572eea86a19eea688a11337ab1c126a55f3b6b655651d1b34891684f688ec561066c504565c80b33499633d968ba26e3cd02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326e5c9644632a370f9b806d23f33fc961b95ae78f7d60067af6ddd10f98faf5224711c007faf543d7508da8bc45d861f3fb4630b0d5a2175fbec7adfad14783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f30ff90c9753239cd0f170ccccb73495e74a222338e9444d122c66e1d4748e5874018dc686dcb7323ee7ef81be38e84f23eefd9408c873c082016a6b56b8653", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7560c7492950cbb8976dec7232abb4a9174ac4f72cd8424001f0dccef0b1d179ad6dc6f261f1023a35afe7b6e62ff6644aac07d45abffbd152f9519913672a1"} build_url:https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011201 build_url: 'https://queue.taskcluster.net/v1/task/F2KaHYnoTtuNM7LzeJmZNg/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/72110e42d1e86d3a0192690ac986572eea86a19eea688a11337ab1c126a55f3b6b655651d1b34891684f688ec561066c504565c80b33499633d968ba26e3cd02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/326e5c9644632a370f9b806d23f33fc961b95ae78f7d60067af6ddd10f98faf5224711c007faf543d7508da8bc45d861f3fb4630b0d5a2175fbec7adfad14783", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f30ff90c9753239cd0f170ccccb73495e74a222338e9444d122c66e1d4748e5874018dc686dcb7323ee7ef81be38e84f23eefd9408c873c082016a6b56b8653", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a7560c7492950cbb8976dec7232abb4a9174ac4f72cd8424001f0dccef0b1d179ad6dc6f261f1023a35afe7b6e62ff6644aac07d45abffbd152f9519913672a1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 05:19:25.535659) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 05:19:25.536007) ========= 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.HaJHQwIBRX/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.jaDA9n93dD/Listeners TMPDIR=/var/folders/zb/gjztv1j9199774r50d3phz4m00000w/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.005306 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 05:19:25.585043) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 05:19:25.585380) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 05:19:25.585709) ========= ========= Total master_lag: 0.20 =========