builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0024 starttime: 1491643007.95 results: success (0) buildid: 20170408004004 builduid: 6d3efddd042842bf8644076bbc09ba8a revision: f6712e7858f391923006b174d27cb919c454a9ce ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:47.946972) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:47.947411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:47.947768) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:48.006434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:48.006821) ========= 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.uakFQjnBZG/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.ShMhTHTxkD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-08 02:16:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-04-08 02:16:48 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.509362 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:48.541275) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:48.541628) ========= 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.uakFQjnBZG/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.ShMhTHTxkD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.029226 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:48.594355) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-08 02:16:48.594712) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f6712e7858f391923006b174d27cb919c454a9ce --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 f6712e7858f391923006b174d27cb919c454a9ce --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uakFQjnBZG/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.ShMhTHTxkD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-08 02:16:48,709 truncating revision to first 12 chars 2017-04-08 02:16:48,709 Setting DEBUG logging. 2017-04-08 02:16:48,709 attempt 1/10 2017-04-08 02:16:48,709 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f6712e7858f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-08 02:16:49,420 unpacking tar archive at: mozilla-aurora-f6712e7858f3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.986897 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-08 02:16:49.605926) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:49.606248) ========= script_repo_revision: f6712e7858f391923006b174d27cb919c454a9ce ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:49.607101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:49.607433) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 02:16:49.636063) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-08 02:16:49.636387) ========= /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.uakFQjnBZG/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.ShMhTHTxkD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:16:49 INFO - MultiFileLogger online at 20170408 02:16:49 in /builds/slave/test 02:16:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:16:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:16:49 INFO - {'append_to_log': False, 02:16:49 INFO - 'base_work_dir': '/builds/slave/test', 02:16:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:16:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:16:49 INFO - 'branch': 'Mozilla-Aurora', 02:16:49 INFO - 'buildbot_json_path': 'buildprops.json', 02:16:49 INFO - 'config_files': ('talos/mac_config.py',), 02:16:49 INFO - 'default_actions': ('clobber', 02:16:49 INFO - 'read-buildbot-config', 02:16:49 INFO - 'download-and-extract', 02:16:49 INFO - 'populate-webroot', 02:16:49 INFO - 'create-virtualenv', 02:16:49 INFO - 'install', 02:16:49 INFO - 'run-tests'), 02:16:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:16:49 INFO - 'download_minidump_stackwalk': True, 02:16:49 INFO - 'download_symbols': 'ondemand', 02:16:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:16:49 INFO - 'tooltool.py': '/tools/tooltool.py', 02:16:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:16:49 INFO - '/tools/misc-python/virtualenv.py')}, 02:16:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:16:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:16:49 INFO - 'gecko_profile': False, 02:16:49 INFO - 'gecko_profile_interval': 0, 02:16:49 INFO - 'installer_path': 'installer.exe', 02:16:49 INFO - 'log_level': 'info', 02:16:49 INFO - 'log_name': 'talos', 02:16:49 INFO - 'log_to_console': True, 02:16:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:16:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:16:49 INFO - 'opt_config_files': (), 02:16:49 INFO - 'pip_index': False, 02:16:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:16:49 INFO - 'cmd': ('bash', 02:16:49 INFO - '-c', 02:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:16:49 INFO - 'enabled': True, 02:16:49 INFO - 'halt_on_failure': False, 02:16:49 INFO - 'name': 'check_screen_resolution'},), 02:16:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:16:49 INFO - 'cmd': ('bash', 02:16:49 INFO - '-c', 02:16:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:16:49 INFO - 'enabled': True, 02:16:49 INFO - 'halt_on_failure': False, 02:16:49 INFO - 'name': 'check_screen_resolution'},), 02:16:49 INFO - 'run_cmd_checks_enabled': True, 02:16:49 INFO - 'suite': 'dromaeojs', 02:16:49 INFO - 'system_bits': '32', 02:16:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:16:49 INFO - 'title': 't-yosemite-r7-0024', 02:16:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:16:49 INFO - 'use_talos_json': True, 02:16:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:16:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:16:49 INFO - 'work_dir': 'build'} 02:16:49 INFO - [mozharness: 2017-04-08 09:16:49.865449Z] Running clobber step. 02:16:49 INFO - Running pre-action listener: _resource_record_pre_action 02:16:49 INFO - Running main action method: clobber 02:16:49 INFO - rmtree: /builds/slave/test/build 02:16:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:16:52 INFO - Running post-action listener: _resource_record_post_action 02:16:52 INFO - [mozharness: 2017-04-08 09:16:52.392950Z] Finished clobber step (success) 02:16:52 INFO - [mozharness: 2017-04-08 09:16:52.393073Z] Running read-buildbot-config step. 02:16:52 INFO - Running pre-action listener: _resource_record_pre_action 02:16:52 INFO - Running main action method: read_buildbot_config 02:16:52 INFO - Using buildbot properties: 02:16:52 INFO - { 02:16:52 INFO - "project": "", 02:16:52 INFO - "product": "firefox", 02:16:52 INFO - "script_repo_revision": "production", 02:16:52 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:16:52 INFO - "repository": "", 02:16:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:16:52 INFO - "buildid": "20170408004004", 02:16:52 INFO - "stage_platform": "macosx64", 02:16:52 INFO - "basedir": "/builds/slave/test", 02:16:52 INFO - "nightly_build": "True", 02:16:52 INFO - "buildnumber": 107, 02:16:52 INFO - "slavename": "t-yosemite-r7-0024", 02:16:52 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:16:52 INFO - "platform": "yosemite_r7", 02:16:52 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:16:52 INFO - "branch": "mozilla-aurora", 02:16:52 INFO - "repo_path": "releases/mozilla-aurora", 02:16:52 INFO - "revision": "f6712e7858f391923006b174d27cb919c454a9ce", 02:16:52 INFO - "pgo_build": "False", 02:16:52 INFO - "builduid": "6d3efddd042842bf8644076bbc09ba8a", 02:16:52 INFO - "slavebuilddir": "test" 02:16:52 INFO - } 02:16:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 02:16:52 INFO - Running post-action listener: _resource_record_post_action 02:16:52 INFO - [mozharness: 2017-04-08 09:16:52.396517Z] Finished read-buildbot-config step (success) 02:16:52 INFO - [mozharness: 2017-04-08 09:16:52.396669Z] Running download-and-extract step. 02:16:52 INFO - Running pre-action listener: _resource_record_pre_action 02:16:52 INFO - Running main action method: download_and_extract 02:16:52 INFO - mkdir: /builds/slave/test/build/tests 02:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:52 INFO - trying https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:52 INFO - Downloading https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 02:16:53 INFO - Downloaded 1199 bytes. 02:16:53 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 02:16:53 INFO - Using the following test package requirements: 02:16:53 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:16:53 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 02:16:53 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 02:16:53 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'jsshell-mac.zip'], 02:16:53 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 02:16:53 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 02:16:53 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 02:16:53 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 02:16:53 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 02:16:53 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 02:16:53 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 02:16:53 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:16:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:16:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:16:53 INFO - Fetch https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:16:55 INFO - Expected file size: 51790281 02:16:55 INFO - Obtained file size: 51790281 02:17:11 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:17:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 02:17:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:17:11 INFO - Fetch https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 02:17:13 INFO - Expected file size: 51790281 02:17:13 INFO - Obtained file size: 51790281 02:17:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 02:17:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:17:28 INFO - Fetch https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 02:17:29 INFO - Expected file size: 14404424 02:17:29 INFO - Obtained file size: 14404424 02:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:29 INFO - trying https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:17:29 INFO - Downloading https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 02:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 02:17:31 INFO - Downloaded 74728571 bytes. 02:17:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:17:31 INFO - mkdir: /builds/slave/test/properties 02:17:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:17:31 INFO - Writing to file /builds/slave/test/properties/build_url 02:17:31 INFO - Contents: 02:17:31 INFO - build_url:https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 02:17:32 INFO - Running post-action listener: _resource_record_post_action 02:17:32 INFO - Running post-action listener: set_extra_try_arguments 02:17:32 INFO - [mozharness: 2017-04-08 09:17:32.178429Z] Finished download-and-extract step (success) 02:17:32 INFO - [mozharness: 2017-04-08 09:17:32.178567Z] Running populate-webroot step. 02:17:32 INFO - Running pre-action listener: _resource_record_pre_action 02:17:32 INFO - Running main action method: populate_webroot 02:17:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:17:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:17:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:17:32 INFO - '--tppagecycles', 02:17:32 INFO - '7'], 02:17:32 INFO - 'tests': ['tsvgm']}}, 02:17:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:17:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:17:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:17:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:17:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:17:32 INFO - 'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:17:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:17:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:17:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:17:32 INFO - 'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['damp', 'tps']}, 02:17:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:17:32 INFO - 'tests': ['damp', 'tps']}, 02:17:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['dromaeo_dom']}, 02:17:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:17:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['basic_compositor_video']}, 02:17:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:17:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['a11yr', 02:17:32 INFO - 'ts_paint', 02:17:32 INFO - 'tpaint', 02:17:32 INFO - 'sessionrestore', 02:17:32 INFO - 'sessionrestore_no_auto_restore', 02:17:32 INFO - 'tabpaint']}, 02:17:32 INFO - 'other-e10s': {'tests': ['a11yr', 02:17:32 INFO - 'ts_paint', 02:17:32 INFO - 'tpaint', 02:17:32 INFO - 'sessionrestore', 02:17:32 INFO - 'sessionrestore_no_auto_restore', 02:17:32 INFO - 'tabpaint']}, 02:17:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:17:32 INFO - 'ts_paint', 02:17:32 INFO - 'tpaint', 02:17:32 INFO - 'sessionrestore', 02:17:32 INFO - 'sessionrestore_no_auto_restore', 02:17:32 INFO - 'tabpaint']}, 02:17:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:17:32 INFO - 'ts_paint', 02:17:32 INFO - 'tpaint', 02:17:32 INFO - 'sessionrestore', 02:17:32 INFO - 'sessionrestore_no_auto_restore', 02:17:32 INFO - 'tabpaint']}, 02:17:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['a11yr', 02:17:32 INFO - 'ts_paint', 02:17:32 INFO - 'tpaint', 02:17:32 INFO - 'sessionrestore', 02:17:32 INFO - 'sessionrestore_no_auto_restore', 02:17:32 INFO - 'tabpaint']}, 02:17:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['a11yr', 02:17:32 INFO - 'ts_paint', 02:17:32 INFO - 'tpaint', 02:17:32 INFO - 'sessionrestore', 02:17:32 INFO - 'sessionrestore_no_auto_restore', 02:17:32 INFO - 'tabpaint']}, 02:17:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['tsvgx', 02:17:32 INFO - 'tsvgr_opacity', 02:17:32 INFO - 'tart', 02:17:32 INFO - 'tscrollx', 02:17:32 INFO - 'cart', 02:17:32 INFO - 'tsvg_static']}, 02:17:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:17:32 INFO - 'tsvgr_opacity', 02:17:32 INFO - 'tart', 02:17:32 INFO - 'tscrollx', 02:17:32 INFO - 'cart', 02:17:32 INFO - 'tsvg_static']}, 02:17:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:17:32 INFO - 'talos_options': ['--disable-e10s'], 02:17:32 INFO - 'tests': ['tp5o']}, 02:17:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:17:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:17:32 INFO - 'talos_options': ['--disable-e10s', 02:17:32 INFO - '--xperf_path', 02:17:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:17:32 INFO - 'tests': ['tp5n']}, 02:17:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:17:32 INFO - 'talos_options': ['--xperf_path', 02:17:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:17:32 INFO - 'tests': ['tp5n']}}, 02:17:32 INFO - 'talos.zip': {'path': '', 02:17:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:17:32 INFO - Running post-action listener: _resource_record_post_action 02:17:32 INFO - [mozharness: 2017-04-08 09:17:32.198077Z] Finished populate-webroot step (success) 02:17:32 INFO - [mozharness: 2017-04-08 09:17:32.198208Z] Running create-virtualenv step. 02:17:32 INFO - Running pre-action listener: _resource_record_pre_action 02:17:32 INFO - Running main action method: create_virtualenv 02:17:32 INFO - Creating virtualenv /builds/slave/test/build/venv 02:17:32 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:17:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:17:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:17:32 INFO - Using real prefix '/tools/python27' 02:17:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:17:33 INFO - Installing distribute.............................................................................................................................................................................................done. 02:17:35 INFO - Installing pip.................done. 02:17:35 INFO - Return code: 0 02:17:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:17:36 INFO - Reading from file tmpfile_stdout 02:17:36 INFO - Output received: 02:17:36 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:17:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:36 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:36 INFO - 'HOME': '/Users/cltbld', 02:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:36 INFO - 'LOGNAME': 'cltbld', 02:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:36 INFO - 'MOZ_NO_REMOTE': '1', 02:17:36 INFO - 'NO_EM_RESTART': '1', 02:17:36 INFO - 'PAGER': '/bin/cat', 02:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:36 INFO - 'PWD': '/builds/slave/test', 02:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:36 INFO - 'SHELL': '/bin/bash', 02:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:36 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:36 INFO - 'USER': 'cltbld', 02:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:36 INFO - 'XPC_FLAGS': '0x0', 02:17:36 INFO - 'XPC_SERVICE_NAME': '0', 02:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:17:36 INFO - Cleaning up... 02:17:36 INFO - Return code: 0 02:17:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:36 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:36 INFO - 'HOME': '/Users/cltbld', 02:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:36 INFO - 'LOGNAME': 'cltbld', 02:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:36 INFO - 'MOZ_NO_REMOTE': '1', 02:17:36 INFO - 'NO_EM_RESTART': '1', 02:17:36 INFO - 'PAGER': '/bin/cat', 02:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:36 INFO - 'PWD': '/builds/slave/test', 02:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:36 INFO - 'SHELL': '/bin/bash', 02:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:36 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:36 INFO - 'USER': 'cltbld', 02:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:36 INFO - 'XPC_FLAGS': '0x0', 02:17:36 INFO - 'XPC_SERVICE_NAME': '0', 02:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:36 INFO - Downloading/unpacking psutil>=3.1.1 02:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:17:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:17:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:17:40 INFO - Installing collected packages: psutil 02:17:40 INFO - Running setup.py install for psutil 02:17:40 INFO - building 'psutil._psutil_osx' extension 02:17:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:17:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:17:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:17:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:17:41 INFO - building 'psutil._psutil_posix' extension 02:17:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:17:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:17:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:17:41 INFO - ^ 02:17:41 INFO - 1 warning generated. 02:17:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:17:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:17:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:17:41 INFO - Successfully installed psutil 02:17:41 INFO - Cleaning up... 02:17:42 INFO - Return code: 0 02:17:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:42 INFO - 'HOME': '/Users/cltbld', 02:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:42 INFO - 'LOGNAME': 'cltbld', 02:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:42 INFO - 'MOZ_NO_REMOTE': '1', 02:17:42 INFO - 'NO_EM_RESTART': '1', 02:17:42 INFO - 'PAGER': '/bin/cat', 02:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:42 INFO - 'PWD': '/builds/slave/test', 02:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:42 INFO - 'SHELL': '/bin/bash', 02:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:42 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:42 INFO - 'USER': 'cltbld', 02:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:42 INFO - 'XPC_FLAGS': '0x0', 02:17:42 INFO - 'XPC_SERVICE_NAME': '0', 02:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:17:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:17:45 INFO - Installing collected packages: mozsystemmonitor 02:17:45 INFO - Running setup.py install for mozsystemmonitor 02:17:45 INFO - Successfully installed mozsystemmonitor 02:17:45 INFO - Cleaning up... 02:17:45 INFO - Return code: 0 02:17:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:45 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:17:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:45 INFO - 'HOME': '/Users/cltbld', 02:17:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:45 INFO - 'LOGNAME': 'cltbld', 02:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:45 INFO - 'MOZ_NO_REMOTE': '1', 02:17:45 INFO - 'NO_EM_RESTART': '1', 02:17:45 INFO - 'PAGER': '/bin/cat', 02:17:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:45 INFO - 'PWD': '/builds/slave/test', 02:17:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:45 INFO - 'SHELL': '/bin/bash', 02:17:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:45 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:45 INFO - 'USER': 'cltbld', 02:17:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:45 INFO - 'XPC_FLAGS': '0x0', 02:17:45 INFO - 'XPC_SERVICE_NAME': '0', 02:17:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:45 INFO - Downloading/unpacking jsonschema==2.5.1 02:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:17:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:17: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 02:17: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 02:17: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 02:17: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 02:17: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 02:17: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 02:17:48 INFO - Downloading functools32-3.2.3-2.tar.gz 02:17:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:17:48 INFO - warning: no files found matching '*.txt' 02:17:48 INFO - no previously-included directories found matching 'build' 02:17:48 INFO - no previously-included directories found matching 'dist' 02:17:48 INFO - no previously-included directories found matching '.git*' 02:17:48 INFO - Installing collected packages: jsonschema, functools32 02:17:48 INFO - Running setup.py install for functools32 02:17:48 INFO - warning: no files found matching '*.txt' 02:17:48 INFO - no previously-included directories found matching 'build' 02:17:48 INFO - no previously-included directories found matching 'dist' 02:17:48 INFO - no previously-included directories found matching '.git*' 02:17:48 INFO - Successfully installed jsonschema functools32 02:17:48 INFO - Cleaning up... 02:17:49 INFO - Return code: 0 02:17:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:49 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:49 INFO - 'HOME': '/Users/cltbld', 02:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:49 INFO - 'LOGNAME': 'cltbld', 02:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:49 INFO - 'MOZ_NO_REMOTE': '1', 02:17:49 INFO - 'NO_EM_RESTART': '1', 02:17:49 INFO - 'PAGER': '/bin/cat', 02:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:49 INFO - 'PWD': '/builds/slave/test', 02:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:49 INFO - 'SHELL': '/bin/bash', 02:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:49 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:49 INFO - 'USER': 'cltbld', 02:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:49 INFO - 'XPC_FLAGS': '0x0', 02:17:49 INFO - 'XPC_SERVICE_NAME': '0', 02:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:17:49 INFO - Cleaning up... 02:17:49 INFO - Return code: 0 02:17:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:49 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:49 INFO - 'HOME': '/Users/cltbld', 02:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:49 INFO - 'LOGNAME': 'cltbld', 02:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:49 INFO - 'MOZ_NO_REMOTE': '1', 02:17:49 INFO - 'NO_EM_RESTART': '1', 02:17:49 INFO - 'PAGER': '/bin/cat', 02:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:49 INFO - 'PWD': '/builds/slave/test', 02:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:49 INFO - 'SHELL': '/bin/bash', 02:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:49 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:49 INFO - 'USER': 'cltbld', 02:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:49 INFO - 'XPC_FLAGS': '0x0', 02:17:49 INFO - 'XPC_SERVICE_NAME': '0', 02:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:49 INFO - Downloading/unpacking blobuploader==1.2.4 02:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:52 INFO - Downloading blobuploader-1.2.4.tar.gz 02:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:17:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:17:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:17:53 INFO - Downloading docopt-0.6.1.tar.gz 02:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:17:53 INFO - Installing collected packages: blobuploader, requests, docopt 02:17:53 INFO - Running setup.py install for blobuploader 02:17:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:17:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:17:53 INFO - Running setup.py install for requests 02:17:54 INFO - Running setup.py install for docopt 02:17:54 INFO - Successfully installed blobuploader requests docopt 02:17:54 INFO - Cleaning up... 02:17:54 INFO - Return code: 0 02:17:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17: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')]} 02:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17: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')]} 02:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:54 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:54 INFO - 'HOME': '/Users/cltbld', 02:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:54 INFO - 'LOGNAME': 'cltbld', 02:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:54 INFO - 'MOZ_NO_REMOTE': '1', 02:17:54 INFO - 'NO_EM_RESTART': '1', 02:17:54 INFO - 'PAGER': '/bin/cat', 02:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:54 INFO - 'PWD': '/builds/slave/test', 02:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:54 INFO - 'SHELL': '/bin/bash', 02:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:54 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:54 INFO - 'USER': 'cltbld', 02:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:54 INFO - 'XPC_FLAGS': '0x0', 02:17:54 INFO - 'XPC_SERVICE_NAME': '0', 02:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:54 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-PTBulL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:54 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-xlFwYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:54 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Letp6u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:54 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-JNy672-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:54 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-N4Udy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-RYyJFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-jdBCw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-mw7sFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-NT0M88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-8yt4Jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-W7ih3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-iRyUej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-8zxBV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:17:55 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-AjvBvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:56 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-cm40Cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:17:56 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-o2F6NQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:17:56 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3TMikA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:17:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:17:56 INFO - Running setup.py install for manifestparser 02:17:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:17:56 INFO - Running setup.py install for mozcrash 02:17:56 INFO - Running setup.py install for mozdebug 02:17:56 INFO - Running setup.py install for mozdevice 02:17:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:17:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:17:56 INFO - Running setup.py install for mozfile 02:17:57 INFO - Running setup.py install for mozhttpd 02:17:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Running setup.py install for mozinfo 02:17:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Running setup.py install for mozInstall 02:17:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Running setup.py install for mozleak 02:17:57 INFO - Running setup.py install for mozlog 02:17:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Running setup.py install for moznetwork 02:17:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:17:57 INFO - Running setup.py install for mozprocess 02:17:58 INFO - Running setup.py install for mozprofile 02:17:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:17:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:17:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:17:58 INFO - Running setup.py install for mozrunner 02:17:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:17:58 INFO - Running setup.py install for mozscreenshot 02:17:58 INFO - Running setup.py install for moztest 02:17:58 INFO - Running setup.py install for mozversion 02:17:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:17:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:17:58 INFO - Cleaning up... 02:17:58 INFO - Return code: 0 02:17:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:17:58 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:58 INFO - 'HOME': '/Users/cltbld', 02:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:58 INFO - 'LOGNAME': 'cltbld', 02:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:58 INFO - 'MOZ_NO_REMOTE': '1', 02:17:58 INFO - 'NO_EM_RESTART': '1', 02:17:58 INFO - 'PAGER': '/bin/cat', 02:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:58 INFO - 'PWD': '/builds/slave/test', 02:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:58 INFO - 'SHELL': '/bin/bash', 02:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:17:58 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:58 INFO - 'USER': 'cltbld', 02:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:58 INFO - 'XPC_FLAGS': '0x0', 02:17:58 INFO - 'XPC_SERVICE_NAME': '0', 02:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-kKu0SM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-euGvPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-fg7eML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-MOV89K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-DOkTCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-kYv7tL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-TiCyId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:17:59 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-WG2ZOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-RQXL9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-JOgWBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-l3emSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-2NwIgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-1cXrWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Mn9fmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-T1HzQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Vufo7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:00 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-b4SSrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:18:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:18:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:18:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:18:03 INFO - Downloading blessings-1.6.tar.gz 02:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:18:03 INFO - Installing collected packages: blessings 02:18:03 INFO - Running setup.py install for blessings 02:18:03 INFO - Successfully installed blessings 02:18:03 INFO - Cleaning up... 02:18:04 INFO - Return code: 0 02:18:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:18:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:04 INFO - Reading from file tmpfile_stdout 02:18:04 INFO - Current package versions: 02:18:04 INFO - blessings == 1.6 02:18:04 INFO - blobuploader == 1.2.4 02:18:04 INFO - docopt == 0.6.1 02:18:04 INFO - functools32 == 3.2.3-2 02:18:04 INFO - jsonschema == 2.5.1 02:18:04 INFO - manifestparser == 1.1 02:18:04 INFO - mozInstall == 1.12 02:18:04 INFO - mozcrash == 1.0 02:18:04 INFO - mozdebug == 0.1 02:18:04 INFO - mozdevice == 0.49 02:18:04 INFO - mozfile == 1.2 02:18:04 INFO - mozhttpd == 0.7 02:18:04 INFO - mozinfo == 0.9 02:18:04 INFO - mozleak == 0.1 02:18:04 INFO - mozlog == 3.4 02:18:04 INFO - moznetwork == 0.27 02:18:04 INFO - mozprocess == 0.25 02:18:04 INFO - mozprofile == 0.28 02:18:04 INFO - mozrunner == 6.13 02:18:04 INFO - mozscreenshot == 0.1 02:18:04 INFO - mozsystemmonitor == 0.3 02:18:04 INFO - moztest == 0.8 02:18:04 INFO - mozversion == 1.4 02:18:04 INFO - psutil == 3.1.1 02:18:04 INFO - requests == 1.2.3 02:18:04 INFO - wsgiref == 0.1.2 02:18:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:04 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:04 INFO - 'HOME': '/Users/cltbld', 02:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:04 INFO - 'LOGNAME': 'cltbld', 02:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:04 INFO - 'MOZ_NO_REMOTE': '1', 02:18:04 INFO - 'NO_EM_RESTART': '1', 02:18:04 INFO - 'PAGER': '/bin/cat', 02:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:04 INFO - 'PWD': '/builds/slave/test', 02:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:04 INFO - 'SHELL': '/bin/bash', 02:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:18:04 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:04 INFO - 'USER': 'cltbld', 02:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:04 INFO - 'XPC_FLAGS': '0x0', 02:18:04 INFO - 'XPC_SERVICE_NAME': '0', 02:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:18:04 INFO - Cleaning up... 02:18:04 INFO - Return code: 0 02:18:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:18: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')]} 02:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18: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')]} 02:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:04 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 0x10aacfb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aad2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8932d90050>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, '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.uakFQjnBZG/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.ShMhTHTxkD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:04 INFO - 'HOME': '/Users/cltbld', 02:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:04 INFO - 'LOGNAME': 'cltbld', 02:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:04 INFO - 'MOZ_NO_REMOTE': '1', 02:18:04 INFO - 'NO_EM_RESTART': '1', 02:18:04 INFO - 'PAGER': '/bin/cat', 02:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:04 INFO - 'PWD': '/builds/slave/test', 02:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:04 INFO - 'SHELL': '/bin/bash', 02:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:18:04 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:04 INFO - 'USER': 'cltbld', 02:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:04 INFO - 'XPC_FLAGS': '0x0', 02:18:04 INFO - 'XPC_SERVICE_NAME': '0', 02:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:18:04 INFO - Cleaning up... 02:18:04 INFO - Return code: 0 02:18:04 INFO - Running post-action listener: _resource_record_post_action 02:18:04 INFO - Running post-action listener: _start_resource_monitoring 02:18:04 INFO - Starting resource monitoring. 02:18:04 INFO - [mozharness: 2017-04-08 09:18:04.847555Z] Finished create-virtualenv step (success) 02:18:04 INFO - [mozharness: 2017-04-08 09:18:04.848579Z] Running install step. 02:18:04 INFO - Running pre-action listener: _resource_record_pre_action 02:18:04 INFO - Running main action method: install 02:18:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:05 INFO - Reading from file tmpfile_stdout 02:18:05 INFO - Detecting whether we're running mozinstall >=1.0... 02:18:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:18:05 INFO - Reading from file tmpfile_stdout 02:18:05 INFO - Output received: 02:18:05 INFO - Usage: mozinstall [options] installer 02:18:05 INFO - Options: 02:18:05 INFO - -h, --help show this help message and exit 02:18:05 INFO - -d DEST, --destination=DEST 02:18:05 INFO - Directory to install application into. [default: 02:18:05 INFO - "/builds/slave/test"] 02:18:05 INFO - --app=APP Application being installed. [default: firefox] 02:18:05 INFO - mkdir: /builds/slave/test/build/application 02:18:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:18:25 INFO - Reading from file tmpfile_stdout 02:18:25 INFO - Output received: 02:18:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:18:25 INFO - Running post-action listener: _resource_record_post_action 02:18:25 INFO - [mozharness: 2017-04-08 09:18:25.153032Z] Finished install step (success) 02:18:25 INFO - [mozharness: 2017-04-08 09:18:25.153210Z] Running run-tests step. 02:18:25 INFO - Running pre-action listener: _resource_record_pre_action 02:18:25 INFO - Running main action method: run_tests 02:18:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:18:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:18:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:18:25 INFO - 2017-04-08 02:18:25.168 screenresolution[1626:9298] starting screenresolution argv=screenresolution get 02:18:25 INFO - 2017-04-08 02:18:25.171 screenresolution[1626:9298] Display 0: 1600x1200x32@60 02:18:25 INFO - 2017-04-08 02:18:25.176 screenresolution[1627:9301] starting screenresolution argv=screenresolution list 02:18:25 INFO - Available Modes on Display 0 02:18:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:18:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:18:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:18:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:18:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:18:25 INFO - Intel Iris: 02:18:25 INFO - Chipset Model: Intel Iris 02:18:25 INFO - Type: GPU 02:18:25 INFO - Bus: Built-In 02:18:25 INFO - VRAM (Dynamic, Max): 1536 MB 02:18:25 INFO - Vendor: Intel (0x8086) 02:18:25 INFO - Device ID: 0x0a2e 02:18:25 INFO - Revision ID: 0x0009 02:18:25 INFO - Displays: 02:18:25 INFO - Display: 02:18:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:18:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:18:25 INFO - Display Serial Number: 1600x1200 60 02:18:25 INFO - Main Display: Yes 02:18:25 INFO - Mirror: Off 02:18:25 INFO - Online: Yes 02:18:25 INFO - Rotation: Supported 02:18:25 INFO - Return code: 0 02:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:18:25 INFO - Python 2.7.3 02:18:25 INFO - Return code: 0 02:18:25 INFO - grabbing minidump binary from tooltool 02:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:25 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 0x10a8add30>, '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 0x10a7d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8a2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:18:25 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:18:25 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:18:25 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:18:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:18:25 INFO - Return code: 0 02:18:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:18:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:18:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:18:25 INFO - ENV: RUST_BACKTRACE is now 1 02:18:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:18:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:18:25 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/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:18:25 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/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uakFQjnBZG/Render', 02:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:18:25 INFO - 'HOME': '/Users/cltbld', 02:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:18:25 INFO - 'LOGNAME': 'cltbld', 02:18:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:18:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:25 INFO - 'MOZ_NO_REMOTE': '1', 02:18:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:18:25 INFO - 'NO_EM_RESTART': '1', 02:18:25 INFO - 'PAGER': '/bin/cat', 02:18:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:25 INFO - 'PWD': '/builds/slave/test', 02:18:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:18:25 INFO - 'RUST_BACKTRACE': '1', 02:18:25 INFO - 'SHELL': '/bin/bash', 02:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ShMhTHTxkD/Listeners', 02:18:25 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 02:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:18:25 INFO - 'USER': 'cltbld', 02:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:25 INFO - 'XPC_FLAGS': '0x0', 02:18:25 INFO - 'XPC_SERVICE_NAME': '0', 02:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:18:25 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/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:18:25 INFO - mozversion application_buildid: 20170408004004 02:18:25 INFO - mozversion application_changeset: f6712e7858f391923006b174d27cb919c454a9ce 02:18:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:18:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:18:25 INFO - mozversion application_name: Firefox 02:18:25 INFO - mozversion application_remotingname: firefox-dev 02:18:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:18:25 INFO - mozversion application_vendor: Mozilla 02:18:25 INFO - mozversion application_version: 54.0a2 02:18:25 INFO - mozversion platform_buildid: 20170408004004 02:18:25 INFO - mozversion platform_changeset: f6712e7858f391923006b174d27cb919c454a9ce 02:18:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:18:25 INFO - mozversion platform_version: 54.0a2 02:18:25 INFO - using testdate: 1491643105 02:18:25 INFO - actual date: 1491643105 02:18:25 INFO - starting webserver on 'localhost:49240' 02:18:25 INFO - SUITE-START | Running 2 tests 02:18:25 INFO - TEST-START | dromaeo_css 02:18:25 INFO - operating with platform_type : mac_ 02:18:25 INFO - Initialising browser for dromaeo_css test... 02:18:25 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUpoAJW/profile http://localhost:49240/getInfo.html) 02:18:28 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 02:18:28 INFO - PROCESS | 1637 | colorDepth:24 02:18:28 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 02:18:28 INFO - PROCESS | 1637 | __metrics 02:18:28 INFO - PROCESS | 1637 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:18:28 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:18:29 INFO - TEST-INFO | 1637: exit 0 02:18:29 INFO - Browser initialized. 02:18:29 INFO - Running cycle 1/1 for dromaeo_css test... 02:18:29 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUpoAJW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:18:41 INFO - PROCESS | 1641 | RSS: Main: 214155264 02:18:41 INFO - PROCESS | 1641 | 02:21:45 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 02:21:47 INFO - PROCESS | 1641 | RSS: Main: 340869120 02:21:47 INFO - PROCESS | 1641 | 02:23:31 INFO - PROCESS | 1641 | ************************* 02:23:31 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:23:31 INFO - PROCESS | 1641 | 02:23:31 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:23:31 INFO - PROCESS | 1641 | Full stack: 02:23:31 INFO - PROCESS | 1641 | ************************* 02:23:31 INFO - PROCESS | 1641 | ************************* 02:23:31 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:23:31 INFO - PROCESS | 1641 | 02:23:31 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:23:31 INFO - PROCESS | 1641 | Full stack: 02:23:31 INFO - PROCESS | 1641 | ************************* 02:23:31 INFO - PROCESS | 1641 | ************************* 02:23:31 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:23:31 INFO - PROCESS | 1641 | 02:23:31 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:23:31 INFO - PROCESS | 1641 | Full stack: 02:23:31 INFO - PROCESS | 1641 | ************************* 02:23:31 INFO - PROCESS | 1641 | ************************* 02:23:31 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 02:23:31 INFO - PROCESS | 1641 | 02:23:31 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:23:31 INFO - PROCESS | 1641 | Full stack: 02:23:31 INFO - PROCESS | 1641 | ************************* 02:24:50 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 02:24:51 INFO - PROCESS | 1641 | RSS: Main: 346759168 02:24:51 INFO - PROCESS | 1641 | 02:27:53 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 02:27:54 INFO - PROCESS | 1641 | RSS: Main: 328224768 02:27:54 INFO - PROCESS | 1641 | 02:30:56 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 02:30:57 INFO - PROCESS | 1641 | RSS: Main: 344018944 02:30:57 INFO - PROCESS | 1641 | 02:34:01 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 02:34:01 INFO - PROCESS | 1641 | RSS: Main: 317947904 02:34:01 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 02:37:05 INFO - PROCESS | 1641 | RSS: Main: 374063104 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | __start_tp_report 02:37:05 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 02:37:05 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 02:37:05 INFO - PROCESS | 1641 | |i|pagename|runs| 02:37:05 INFO - PROCESS | 1641 | |0;dojo.html;3040.2095455181648;3362.428387174181;3364.7308215342773;3391.287829555793;3424.589049314081;5905.468507834294;5963.3738457462;5973.253343332083;5975.223220981266;5999.340072592011;5939.732712027958;6010.038393856973;6012.669303188323;6025.668588227646;6037.667928263943;5340.359156901177;5384.165454354581;5400.972995135038;5441.673499590018;5460.863478413032;5293.04725149473;5293.735313234342;5339.919901201466;5488.902219556105;5535.197396377523;5351.277577527023;5382.757775900094;5385.299911011563;5396.19057141428;5404.405515393323;5360.544353729938;5367.087595108841;5370.087085195487;5372.57019438444;5378.731063446812;5207;5322.308099947021;5322.308099947021;5326.414094449608;5353.812616558441;5884.617499862496;5913.852153696149;5931.347551769302;5934.347221805598;5962.791302304398;6158.291796443376;6212.65830379329;6215.471684906788;6256.937430625727;6262.060690896356;6302.842428939313;6333.588316759442;6339.239291285029;6341.524385671093;6342.936570634327;4948.331975183376;5992.43071908168;6026.005709058;6045.516358691294;6049.183360246355;6206.689665516707;6229.626222426669;6284.962981108158;6347.873042539123;6401.167848179707;6337.841553961096;6342.763161183617;6352.650604216812;6361;6366.522510811707;6323.747050117944;6326.49388048955;6327.272363678236;6331.923572992602;6346.555741098171;6166.1983942087245;6232.657203853832;6287.49700023997;6291.905621415683;6314;6219.564630475823;6223.408776166257;6272.153259309935;6307.936920630735;6318.71565779541;5152.098382783073;5159.2003239497935;5162.277281180638;5164.380274367025;5186.170212765939;4392.604665580113;4503.864884053483;4514.525974772667;4548.909021819545;4556.270996640522;5843.707814609253;5857.4435428634215;5914.0537513998415;5947.078202878561;5969.134475501115;6316.084167795644;6321.1150438938585;6321.683915804191;6331.68341582919;6337.112804207414;5951.851203719942;5957.285125784933;5968.22413086296;5970.223870896755;5986.5210783137245;5417.83746487606;5435.701036442954;5500.4499550044675;5576.386597474275;5641.689707066069;6553.836154096186;6579.736810527621;6600.735970561123;6604.3725846044545;6610.173728283965;6357.078223657636;6360.395762402564;6360.745570177141;6361.968190159019;6370.076338930737;6260.311365749855;6524.314946930694;6615.735370585123;6619.503537234823;6637.4690024798865;6217.347178546188;6230.314665386894;6231.875362492816;6237.376262373727;6251.6561589113035;5695.71846334588;5975.880482390415;6308.873822523432;6309.7160627770945;6336.493080553452;6242.625442473466;6253;6293.55945083858;6306.716197771019;6310.6213627182515;6278.0269058296035;6298.275698294755;6306.990881458944;6309.526785491014;6311.337309582428;5325.748449114532;5824.679642619697;6133.355997620186;6143.354947730424;6143.784967526115;6114.723190878713;6144.832481828438;6229.595076320024;6310.716017779303;6334.524910631629;6138.447539721446;6139.539534534839;6141.140240366263;6144.38556144382;6167.599106058092 02:37:05 INFO - PROCESS | 1641 | |1;ext.html;754.2721273970619;867.4925168776264;881.5107615273521;884.1158841158837;963.2534785541209;30773.38453230937;37109.443358349614;38828.835134946;38842.61157388425;38851.028724281874;37439.43840842389;37501;37581;37599.62400376002;37626.62373376265;7331.486795924261;7507.474476786627;7717.884231736529;7873.133955264916;7876.921230787676;10897.727556811104;11146.442677866115;11162.832557511643;11193.832092518578;11269.718257043598;11052.281601695864;11120.833187502194;11126;11129.443527823616;11132.220744547885;7291.744788932362;7476.252374762535;11064.225504214708;11101.44492775366;11112.222144449892;7302.1967583565765;7344.963275183643;7348.1182258129365;10651.627193048284;10940;38273.23453530914;38483;38526.614733852584;38537.14628537152;38598.22803543913;3770.4909837171904;3775.735698501106;3776.8111594420443;3801.3293723732777;3957.129431525089;22839.885800571057;26560.468790624273;27039.594406083925;28214.576781348296;28276.293092672848;3052.4047810676925;3055.4500189965797;3090.428270769892;3091.3971775503787;3208.470602350609;264196.03705944616;318122;318599;320855;321026;40493.59506404957;40651.79674101611;41944.370834437505;43502.34746478805;50790.49209507932;43627.56372436298;43854.12291754147;43882.56117438849;43944;51941.48058519442;14524.564263072125;14603.561893143222;14615.415383384758;14626.48807291761;14634.560963171121;12636.178648387826;12768;12797.296148711725;12815.743685126432;12821.23072615646;11473.827892581618;11543.249688770204;11597.362145081932;11650.41747912601;11653.242539234923;5447.4552544746;5552.500274975271;5553.666779993213;5569.136783798518;5589.329280486368;931.9487428191379;951.9857202141974;957.6504575829912;962.6630679262203;981.4444106091781;26276;27000;27409;27429.90280388802;27643.723562764517;1044.3942513342242;1045.8431235314763;1056.1339701444895;1065.3075500924306;1076.3380520979697;11948.701282468008;13669.111507751964;14078.10084285069;14288.57134285973;14480.493182738554;362117;363019;363775;365463;367875;53163.73418132962;53455.46544534505;53488.19767703488;53556.196657050175;53659.73170134125;1417.8369487508862;1476.8153980752406;1477.4385733421232;1479.3416929466284;1480.2598700649676;24739.84425375754;26401.735982639926;26711.86644066845;26855.731442685323;26871.77538695369;38012.429813553375;43611.9097022577;43650.56349436465;43719.125617488105;43745.78127109344;380301;381215;381686.0915695404;382055;382739.0863045667;27209;27694.44611107807;28575.714242857306;31946.520802187522;32815;34092.488612670335;42188.78905605452;42788.78605606952;42858.928526785836;46093.53906460892;28454.43091138124;28987.56518652264;29603.407931841673;30211.697883021767;30276.54585181268;30763.846180768953;30829;30848;30907;30935.834491586273;27032.45935081248;35258;35689.82155089208;35925.1018724534;36198.09504762297;27469.17592472229;28466.430671386872;29262.17480964511;29373.559396609497;29497.85251073731;22659.886700566392;22686;23011;23059.538809223384;23067.538649226586 02:37:05 INFO - PROCESS | 1641 | |2;jquery.html;422.6090865948996;427.9978600106999;429.7932281125155;434.55675211284483;439.63071020342903;18931.99999999998;19864.205431782728;21243.831589262583;21462.248821291258;21522.56954860906;20874.582508349824;21109.944502774837;22688.546229075408;22964;22998.88500557495;6210.223722034746;6754;6761.594304341731;6763.8309042274095;6777.728890844361;6316.399942005502;6558.704858281365;6619.338066193366;6619.867602647944;6621.768238111667;6389.265234498046;6621.172353455818;6626.469882409396;6629.2376376716575;6644.867102657944;5717.342505611846;6586.571872828251;6601.768938087192;6692.665366731644;6693.698783554752;6350.110984462179;6427.132337134521;6451.7741879034475;6522.119513865615;6523.706433210469;25440.618390724154;25480.872595637087;26296;26488.94044238228;26771.73228267712;3289.96710032899;3332.7500437467043;3404.14896275931;3408.744344174197;3541.3448512025357;15364.462243821412;15694.372225110981;15707.293171807296;15714.842851571453;15727.13500757458;1988.8906110163934;2051.753789545249;2059.083707750052;2110.145391116585;2110.6939493773316;97574.02425975792;98494;98822;147021;155684;17172.828271717124;18908.054597270082;19550.608987820164;19941.800581994285;20457.38627841167;32721.854735084158;33118.50322245168;33348.66651333504;33397.165070872936;33413.83293083519;9930;10228.335158214692;10420;10424.5830166794;10608;8615.267702245254;8619.741407757778;8626.482411055356;8778.60496277669;8801.119888011275;9278.768030799283;9343.159115679622;9487.241020718326;9519.952400238093;9533.237341012702;3899.512560929884;3975.264576053439;3995.680345572347;4004;4014.417909403179;947.4552132523827;988.5798535622332;991.4249735153594;1019.6176433837311;1028.3161697471248;15136.470223542123;21072.578548428944;21577.67633485499;21622.35132946014;21639.563555049852;998.4957596413765;1017.4505766885799;1018.1600179851652;1030.4281123976266;1030.7577719235974;10681.679549613524;11075.566574361974;11106.500207490682;11172.547568816002;11180.832287515694;677401;682645;687656;692244;693352;91940.54029729942;94349;122569;152077;156300;1165.1960147498198;1179.6696924861053;1222.2727477151082;1227.183922691418;1279.2836011833404;20037.599248014667;20282.492937676085;20875.851828149596;20964.790352096283;21028.26401075955;62917;63409.2931636155;63782.3621763795;65293.367665808735;65388;89441.55279223563;89506.55246723724;91216.08783912077;92277.07722922953;92554;9870.802583948425;11388.886111138783;11497.08023358146;11537.250078744855;11905.642830715093;10704.946475267574;10706.304090234109;13073.673158171121;13316.800247996482;13473.528426505418;10937.453127343442;11069.612563560237;11284.887151128383;11353.716157096138;11523;10478.842817357594;10576.947115264375;10583;10925.453727313761;10927.453627318444;11263.718407039889;11441.771164576496;11461.885381146416;11644.708882278012;11699.707507312385;11506.424678765861;11800.76398472043;11821.40892955366;11928.343941083323;11933.940330298641;9286.442813431217;11078.889211107786;11228.473593441737;11231.943840280746;11384 02:37:05 INFO - PROCESS | 1641 | |3;mootools.html;1519.3998370643603;1545.235108621232;1569.788078609388;1570.246281784743;1595.5771720494072;10486.265961382696;11024.118070554347;11031.944840275808;11040.779184416308;11058.668239952804;10700.678979630624;10769.784604307928;11074.280171788829;11124;11141;1948.9805101948975;2437.987810060947;2440.2923152285816;2444.963325550118;2446.9143579974702;2067.782882797307;2328.4178955261186;2416.299273210767;2438.1953955194745;2451.779339859412;2355.8350915435926;2412.8311018228733;2424.6726691896542;2427.1383658801137;2427.4780922101745;2320.88395580222;2367.277980216042;2370.7866292033714;2375.845570037942;2411.843230190032;2377.2986968844234;2393.8563686178886;2402.423418379594;2408.843425177358;2409.0845478717974;10927.836082458769;11232.382218977953;11233.932776386231;11242.606508772154;11244.100471962305;5416.91874621881;5467.289252397202;5758.3665796762325;5903.793367232126;5920.5559583031445;3458.948115778265;3561.5013898054294;3601.837917293702;3636.8727094551564;3645.580758212813;1176.9823452648218;1185.158537438411;1216.7140721930339;1229.8893099621077;1234.888860002595;12588.937055314756;13233.139845909986;13573.321333933263;13867.098638588359;14937.477188298357;4503.707259028152;4672.509386514367;4691.3432119503295;4703;4707.152712511713;11344.886551134383;11376.65870023884;11382.601608943647;11383.14626403023;11390.633124025004;4845.854624361275;5048.040872234264;5060.3168572242275;5065.518775716301;5078.822241221539;4444.977775111169;5015.924761128586;5262.026525092793;5292.52367286946;5292.655977361459;3776.3580191367532;3783.905402364963;3862.4206369044305;3868.458415821787;3870.767753934773;1023.1303392116641;1025.1035584169226;1034.2088302448633;1034.8602938603342;1037.9636712715019;900.4912224593215;912.9520104791003;923.3721069672687;931.7437704631145;969.2585172343163;6410.391012853772;6416.454601358937;6609.900851487231;6611.669416529155;6714.597124172467;943.1417410156724;950.3870003847479;951.9333646644668;965.6571916969375;1006.1799633298838;3844.9250184708094;4152.979235103805;4188.81150348235;4282.379055037064;4299;11482.94258528702;13351.532696355775;13494.662633434047;13510.256935868425;13520.661983450493;13069.019823513085;13309.73380532384;13444.731105377838;13487.055906086676;13488.5279015234;9435.811283774423;9918.851217231748;9933.552990115462;10028.598856045823;10166;6380.298167201511;6431.292557818542;6509.218893732783;6555.508336874658;6606;11652.766944661229;12054;12067.57763478274;12100.636980890587;12194.329311887932;10648.946755266174;10833.945830270799;12225.69435764113;12236.816447753468;12256.877431225574;8958.417702849294;9023.684171053981;9063.954680226556;9196.724098277062;9269.397489163182;10026.548805303924;10244.795104098026;10248.948755256177;10252.743681408025;10268.691939241835;8490.533020683675;8618.181272779077;8722.738317850475;8741.91258087411;8745.606447709995;8455.112213217524;8488.65709782781;8488.70289539888;8531.573421329032;8609.139086091342;6547.70535325902;7874.527528348317;7883.172266912121;7883.763487095396;7907.762767116996;5723.484886360247;5724.484796368347;5745.3105627324985;5919.733611987557;5936.614120082181;5763.4812866842185;5800.361960184461;5961.552883533665;5991.191189189578;6135.355787642234 02:37:05 INFO - PROCESS | 1641 | |4;prototype.html;329.0884250625767;374.36171327885955;386.497553180865;388.70458451576803;388.9611038896109;4430.867073987782;4497.752623605708;5035.949640503594;5200.8699782505455;5208.088584497712;4404.4494438195225;4949.727764972934;5031.748412579365;5031.924521132177;5155.968806238758;4394.560543945612;4407.537208593099;4411.889702757425;4434.357018232355;4444.377787109807;3931.7051221158526;3962.3264045112255;4165.541790403053;4385;4452.755098469583;3931.1720050176664;4379.715318504303;4382.846600368987;4408.97795511022;4410.448693913261;3863.420486926955;4201.432806571104;4278.5184592936175;4413.940654242959;4429.13631841791;4033.743625900548;4141.716068018924;4259.467566554181;4309.547497512747;4311.378442108644;4025.3156963316305;4131.793410329502;4539.500654927955;4849.787553111722;4943.577646953003;4424.960134368421;4608.612669687776;4621.399218101626;4737.360456338385;5155.066849931276;5038.773255203525;5259.421463639035;5460.290162278879;5474.534664553517;5521.861953451196;4507.588440184486;4723.7245943595035;4743.7429081293485;4743.786529606176;4792.353032340624;4767.809287628525;5143.485651434864;5720.370759216483;5763.740631671585;5815.360310365899;4398.428204333481;4491.3487544306545;4621.884452888705;4925.975370123127;4945.36802855057;4475.060237350193;4496.5503449655425;4654.138344662095;4709.693869898514;5001.524855138756;4383.802728877208;4612.310151878551;5136.306598609214;5137.486251374833;5227.372715274191;4643.698159619614;4879.56083952446;5090.567301779391;5346.4653534647005;5390.598444404404;1520.217088199578;1607.710612089835;1635.1905806625796;1704.3267909175806;1706.7354560043213;1340.892728581711;1376.9173849569058;1394.5398018654023;1396.413506327345;1423.6939058102557;4668.52941323482;4928.704277743347;5078.847634570969;5107.552402128548;5131.307273518102;5010.799568017239;5100.38795344561;5234.555062819627;5307.867303317448;5344.545713614386;4626.421697287839;4705.85882423521;4773.928391074067;5038.715127642501;5081.720505372166;2417.9844465324604;2524.863811284929;2651.4166883286034;2911.3740545782757;2943.983371390604;5551.944480555224;5690.614084619645;5914.201582786355;5929.970350148222;5949.9107513387335;5139.948600513947;5585.3297604287745;5719.456651618089;5844.532437404998;5874.236349274567;5319.951818549809;5426.035177812258;5442.319710036246;5783.334916484574;5944.740998420572;4938.827141050046;5429.945700542944;5498.560115190695;5654;5675.120356344773;4748.335233067304;4849.612031037579;5180.513353590373;5385.946140538545;5603.831885043455;4983.476734942924;5841.211436456024;5853.619514731528;5861.706914654165;5864.413558644272;3802.885913422602;3876.5735769065946;4137.586241375839;4196.223698615766;4458.308962110878;4734.768960070338;5485.556699073763;5655.786439102894;5676.801311954062;5697.4872261495;4509.413776209072;4635.9304610431545;5430.968116057937;5434.266374039454;5470.863228419162;4639.211334073283;5068.746562671926;5268.81559145428;5445.346558413015;5509.228707980806;4355.73865568067;4914.557689807934;5259.710715910662;5287.973560132328;5362.222477740784;4785.665003449725;5065.442801291929;5442.809501667422;5486.506214440719;5513.61404701667;5158.936425429858;5191.065608190562;5309.840704778863;5325.760340784752;5326.360836699466 02:37:05 INFO - PROCESS | 1641 | |5;yui.html;715.4348065028628;740.2560426771095;775;811.1847593168862;811.7321283976287;15074.698506029887;15912.204389780523;15944.681106377866;15948.362065517395;15969.121698306588;14441.350139243734;14443.205623690692;14481.420743170287;14484.782728259059;14606;893.2809088683607;922.9769255768609;930.3534043839534;933.8645896345026;934.1592566689999;909.1090731083542;912.8174365126968;921.71426857674;921.870938068671;923.9214666753334;962.4177372689527;962.6486332488661;963.6723514005237;964.7973925475624;966.7099870038995;955.3599088610648;955.9091886270793;960.4477425480377;961.0005594182043;961.8076384723014;723.7249845058844;724.999500689051;726.5822152262417;726.9491135620456;727.3344889426169;13973.161610303414;14216.431342746278;14599;15698.293576789072;15721.842781572152;13881.722365552734;13938.442462301497;14108.506202282872;14130.92934535331;14177.43290268388;11625.70935726605;11853.525858965631;11887.699182193772;11889.702757431047;11922.821157682642;3583.2475180212186;3622.2212224371888;3639.7452178347526;3712.517372741553;3762.435634654824;96559.03440965571;104593;106086.46956765244;107123;107632.84734305348;9237.261019118454;10104.949475252577;10605.522751476203;10699.19007530346;10775.299605525615;21846.89076554607;22406.103755849585;22533.32400027969;22555.774442255366;22574.887125564266;7782.49413788102;7795.571243581544;7798;7901.920980790233;7988.80027999293;4265.55211702773;4319.827206911688;4426.159029784395;4434.246178149722;4465.553444655573;2919.226405002676;3516.6659167379057;3526.1713497328105;3527.894163175109;3553.6091029986683;614.8001899382718;628.8207860759661;645.9128017717642;654.4633400611548;655.6426747422666;653.5033374635217;663.3996233408773;680.8672308899717;684.3430306905327;720.7333286683961;9954.552045157985;10386;10441.791164176826;10515.316504427188;10569.894301057046;681.9318068193141;689.8482333886637;696.8362434827811;699.4858778797579;708.840510885057;4785;5064.417591976896;5073.518015788494;5082.695038297714;5082.720450375265;82606.58696706478;84689;87143.56428217945;90480;97324.54013189807;68083.3191668087;70601;70969.64515177494;71753.6412317935;82221;71950;73657.89513157307;73811.7856464307;74992.25007749852;75241;10497.685069447623;11048.337099774039;11049.28179668319;11087.390193539433;11264.887351126712;47975.28037079516;48473.5152648469;50559.49440505548;50777;51285.230721539956;54366;62254;62860.68569657122;63121.68439157775;63318.68340658267;12356.62930112098;12540.498380064695;12866;12918;13115.081944264191;13876.72246555043;13941.651458713613;14211.147331160164;14490.637734056732;15103.924480377527;13001.108304294445;13794.37925293344;13817;13823.51617693376;14062;13411.530596429078;13570.38933248026;13613.65965850822;13739.931300343434;13855.72288554203;12597.067296669762;13432.39554220043;13492.93253533726;13546.661333466742;13685;9112.469486176811;9611.471369074488;9659.130678238993;9673.806523869624;9784.804303914025;11049.392283424168;11117.722056948318;11388.373639449579;11401.429928503707;11410.087193024705 02:37:05 INFO - PROCESS | 1641 | __end_tp_report 02:37:05 INFO - PROCESS | 1641 | __start_cc_report 02:37:05 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,2399 02:37:05 INFO - PROCESS | 1641 | __end_cc_report 02:37:05 INFO - PROCESS | 1641 | __startTimestamp1491644225414__endTimestamp 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | ------- Summary: start ------- 02:37:05 INFO - PROCESS | 1641 | Number of tests: 6 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5879.67 Median:6139.54 stddev:657.53 (10.7%) stddev-sans-first:620.67 02:37:05 INFO - PROCESS | 1641 | Values: 3040.2 3362.4 3364.7 3391.3 3424.6 5905.5 5963.4 5973.3 5975.2 5999.3 5939.7 6010.0 6012.7 6025.7 6037.7 5340.4 5384.2 5401.0 5441.7 5460.9 5293.0 5293.7 5339.9 5488.9 5535.2 5351.3 5382.8 5385.3 5396.2 5404.4 5360.5 5367.1 5370.1 5372.6 5378.7 5207.0 5322.3 5322.3 5326.4 5353.8 5884.6 5913.9 5931.3 5934.3 5962.8 6158.3 6212.7 6215.5 6256.9 6262.1 6302.8 6333.6 6339.2 6341.5 6342.9 4948.3 5992.4 6026.0 6045.5 6049.2 6206.7 6229.6 6285.0 6347.9 6401.2 6337.8 6342.8 6352.7 6361.0 6366.5 6323.7 6326.5 6327.3 6331.9 6346.6 6166.2 6232.7 6287.5 6291.9 6314.0 6219.6 6223.4 6272.2 6307.9 6318.7 5152.1 5159.2 5162.3 5164.4 5186.2 4392.6 4503.9 4514.5 4548.9 4556.3 5843.7 5857.4 5914.1 5947.1 5969.1 6316.1 6321.1 6321.7 6331.7 6337.1 5951.9 5957.3 5968.2 5970.2 5986.5 5417.8 5435.7 5500.4 5576.4 5641.7 6553.8 6579.7 6600.7 6604.4 6610.2 6357.1 6360.4 6360.7 6362.0 6370.1 6260.3 6524.3 6615.7 6619.5 6637.5 6217.3 6230.3 6231.9 6237.4 6251.7 5695.7 5975.9 6308.9 6309.7 6336.5 6242.6 6253.0 6293.6 6306.7 6310.6 6278.0 6298.3 6307.0 6309.5 6311.3 5325.7 5824.7 6133.4 6143.4 6143.8 6114.7 6144.8 6229.6 6310.7 6334.5 6138.4 6139.5 6141.1 6144.4 6167.6 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:49098.68 Median:318360.50 stddev:93150.97 (29.3%) stddev-sans-first:93341.56 02:37:05 INFO - PROCESS | 1641 | Values: 754.3 867.5 881.5 884.1 963.3 30773.4 37109.4 38828.8 38842.6 38851.0 37439.4 37501.0 37581.0 37599.6 37626.6 7331.5 7507.5 7717.9 7873.1 7876.9 10897.7 11146.4 11162.8 11193.8 11269.7 11052.3 11120.8 11126.0 11129.4 11132.2 7291.7 7476.3 11064.2 11101.4 11112.2 7302.2 7345.0 7348.1 10651.6 10940.0 38273.2 38483.0 38526.6 38537.1 38598.2 3770.5 3775.7 3776.8 3801.3 3957.1 22839.9 26560.5 27039.6 28214.6 28276.3 3052.4 3055.5 3090.4 3091.4 3208.5 264196.0 318122.0 318599.0 320855.0 321026.0 40493.6 40651.8 41944.4 43502.3 50790.5 43627.6 43854.1 43882.6 43944.0 51941.5 14524.6 14603.6 14615.4 14626.5 14634.6 12636.2 12768.0 12797.3 12815.7 12821.2 11473.8 11543.2 11597.4 11650.4 11653.2 5447.5 5552.5 5553.7 5569.1 5589.3 931.9 952.0 957.7 962.7 981.4 26276.0 27000.0 27409.0 27429.9 27643.7 1044.4 1045.8 1056.1 1065.3 1076.3 11948.7 13669.1 14078.1 14288.6 14480.5 362117.0 363019.0 363775.0 365463.0 367875.0 53163.7 53455.5 53488.2 53556.2 53659.7 1417.8 1476.8 1477.4 1479.3 1480.3 24739.8 26401.7 26711.9 26855.7 26871.8 38012.4 43611.9 43650.6 43719.1 43745.8 380301.0 381215.0 381686.1 382055.0 382739.1 27209.0 27694.4 28575.7 31946.5 32815.0 34092.5 42188.8 42788.8 42858.9 46093.5 28454.4 28987.6 29603.4 30211.7 30276.5 30763.8 30829.0 30848.0 30907.0 30935.8 27032.5 35258.0 35689.8 35925.1 36198.1 27469.2 28466.4 29262.2 29373.6 29497.9 22659.9 22686.0 23011.0 23059.5 23067.5 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:39648.75 Median:22826.27 stddev:113846.69 (498.8%) stddev-sans-first:114127.96 02:37:05 INFO - PROCESS | 1641 | Values: 422.6 428.0 429.8 434.6 439.6 18932.0 19864.2 21243.8 21462.2 21522.6 20874.6 21109.9 22688.5 22964.0 22998.9 6210.2 6754.0 6761.6 6763.8 6777.7 6316.4 6558.7 6619.3 6619.9 6621.8 6389.3 6621.2 6626.5 6629.2 6644.9 5717.3 6586.6 6601.8 6692.7 6693.7 6350.1 6427.1 6451.8 6522.1 6523.7 25440.6 25480.9 26296.0 26488.9 26771.7 3290.0 3332.8 3404.1 3408.7 3541.3 15364.5 15694.4 15707.3 15714.8 15727.1 1988.9 2051.8 2059.1 2110.1 2110.7 97574.0 98494.0 98822.0 147021.0 155684.0 17172.8 18908.1 19550.6 19941.8 20457.4 32721.9 33118.5 33348.7 33397.2 33413.8 9930.0 10228.3 10420.0 10424.6 10608.0 8615.3 8619.7 8626.5 8778.6 8801.1 9278.8 9343.2 9487.2 9520.0 9533.2 3899.5 3975.3 3995.7 4004.0 4014.4 947.5 988.6 991.4 1019.6 1028.3 15136.5 21072.6 21577.7 21622.4 21639.6 998.5 1017.5 1018.2 1030.4 1030.8 10681.7 11075.6 11106.5 11172.5 11180.8 677401.0 682645.0 687656.0 692244.0 693352.0 91940.5 94349.0 122569.0 152077.0 156300.0 1165.2 1179.7 1222.3 1227.2 1279.3 20037.6 20282.5 20875.9 20964.8 21028.3 62917.0 63409.3 63782.4 65293.4 65388.0 89441.6 89506.6 91216.1 92277.1 92554.0 9870.8 11388.9 11497.1 11537.3 11905.6 10704.9 10706.3 13073.7 13316.8 13473.5 10937.5 11069.6 11284.9 11353.7 11523.0 10478.8 10576.9 10583.0 10925.5 10927.5 11263.7 11441.8 11461.9 11644.7 11699.7 11506.4 11800.8 11821.4 11928.3 11933.9 9286.4 11078.9 11228.5 11231.9 11384.0 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6569.79 Median:2445.94 stddev:4049.94 (165.6%) stddev-sans-first:4043.52 02:37:05 INFO - PROCESS | 1641 | Values: 1519.4 1545.2 1569.8 1570.2 1595.6 10486.3 11024.1 11031.9 11040.8 11058.7 10700.7 10769.8 11074.3 11124.0 11141.0 1949.0 2438.0 2440.3 2445.0 2446.9 2067.8 2328.4 2416.3 2438.2 2451.8 2355.8 2412.8 2424.7 2427.1 2427.5 2320.9 2367.3 2370.8 2375.8 2411.8 2377.3 2393.9 2402.4 2408.8 2409.1 10927.8 11232.4 11233.9 11242.6 11244.1 5416.9 5467.3 5758.4 5903.8 5920.6 3458.9 3561.5 3601.8 3636.9 3645.6 1177.0 1185.2 1216.7 1229.9 1234.9 12588.9 13233.1 13573.3 13867.1 14937.5 4503.7 4672.5 4691.3 4703.0 4707.2 11344.9 11376.7 11382.6 11383.1 11390.6 4845.9 5048.0 5060.3 5065.5 5078.8 4445.0 5015.9 5262.0 5292.5 5292.7 3776.4 3783.9 3862.4 3868.5 3870.8 1023.1 1025.1 1034.2 1034.9 1038.0 900.5 913.0 923.4 931.7 969.3 6410.4 6416.5 6609.9 6611.7 6714.6 943.1 950.4 951.9 965.7 1006.2 3844.9 4153.0 4188.8 4282.4 4299.0 11482.9 13351.5 13494.7 13510.3 13520.7 13069.0 13309.7 13444.7 13487.1 13488.5 9435.8 9918.9 9933.6 10028.6 10166.0 6380.3 6431.3 6509.2 6555.5 6606.0 11652.8 12054.0 12067.6 12100.6 12194.3 10648.9 10833.9 12225.7 12236.8 12256.9 8958.4 9023.7 9064.0 9196.7 9269.4 10026.5 10244.8 10248.9 10252.7 10268.7 8490.5 8618.2 8722.7 8741.9 8745.6 8455.1 8488.7 8488.7 8531.6 8609.1 6547.7 7874.5 7883.2 7883.8 7907.8 5723.5 5724.5 5745.3 5919.7 5936.6 5763.5 5800.4 5961.6 5991.2 6135.4 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4581.92 Median:4920.27 stddev:1229.92 (25.0%) stddev-sans-first:1191.23 02:37:05 INFO - PROCESS | 1641 | Values: 329.1 374.4 386.5 388.7 389.0 4430.9 4497.8 5035.9 5200.9 5208.1 4404.4 4949.7 5031.7 5031.9 5156.0 4394.6 4407.5 4411.9 4434.4 4444.4 3931.7 3962.3 4165.5 4385.0 4452.8 3931.2 4379.7 4382.8 4409.0 4410.4 3863.4 4201.4 4278.5 4413.9 4429.1 4033.7 4141.7 4259.5 4309.5 4311.4 4025.3 4131.8 4539.5 4849.8 4943.6 4425.0 4608.6 4621.4 4737.4 5155.1 5038.8 5259.4 5460.3 5474.5 5521.9 4507.6 4723.7 4743.7 4743.8 4792.4 4767.8 5143.5 5720.4 5763.7 5815.4 4398.4 4491.3 4621.9 4926.0 4945.4 4475.1 4496.6 4654.1 4709.7 5001.5 4383.8 4612.3 5136.3 5137.5 5227.4 4643.7 4879.6 5090.6 5346.5 5390.6 1520.2 1607.7 1635.2 1704.3 1706.7 1340.9 1376.9 1394.5 1396.4 1423.7 4668.5 4928.7 5078.8 5107.6 5131.3 5010.8 5100.4 5234.6 5307.9 5344.5 4626.4 4705.9 4773.9 5038.7 5081.7 2418.0 2524.9 2651.4 2911.4 2944.0 5551.9 5690.6 5914.2 5930.0 5949.9 5139.9 5585.3 5719.5 5844.5 5874.2 5320.0 5426.0 5442.3 5783.3 5944.7 4938.8 5429.9 5498.6 5654.0 5675.1 4748.3 4849.6 5180.5 5385.9 5603.8 4983.5 5841.2 5853.6 5861.7 5864.4 3802.9 3876.6 4137.6 4196.2 4458.3 4734.8 5485.6 5655.8 5676.8 5697.5 4509.4 4635.9 5431.0 5434.3 5470.9 4639.2 5068.7 5268.8 5445.3 5509.2 4355.7 4914.6 5259.7 5288.0 5362.2 4785.7 5065.4 5442.8 5486.5 5513.6 5158.9 5191.1 5309.8 5325.8 5326.4 02:37:05 INFO - PROCESS | 1641 | 02:37:05 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19326.00 Median:4449.90 stddev:26671.69 (599.4%) stddev-sans-first:26709.90 02:37:05 INFO - PROCESS | 1641 | Values: 715.4 740.3 775.0 811.2 811.7 15074.7 15912.2 15944.7 15948.4 15969.1 14441.4 14443.2 14481.4 14484.8 14606.0 893.3 923.0 930.4 933.9 934.2 909.1 912.8 921.7 921.9 923.9 962.4 962.6 963.7 964.8 966.7 955.4 955.9 960.4 961.0 961.8 723.7 725.0 726.6 726.9 727.3 13973.2 14216.4 14599.0 15698.3 15721.8 13881.7 13938.4 14108.5 14130.9 14177.4 11625.7 11853.5 11887.7 11889.7 11922.8 3583.2 3622.2 3639.7 3712.5 3762.4 96559.0 104593.0 106086.5 107123.0 107632.8 9237.3 10104.9 10605.5 10699.2 10775.3 21846.9 22406.1 22533.3 22555.8 22574.9 7782.5 7795.6 7798.0 7901.9 7988.8 4265.6 4319.8 4426.2 4434.2 4465.6 2919.2 3516.7 3526.2 3527.9 3553.6 614.8 628.8 645.9 654.5 655.6 653.5 663.4 680.9 684.3 720.7 9954.6 10386.0 10441.8 10515.3 10569.9 681.9 689.8 696.8 699.5 708.8 4785.0 5064.4 5073.5 5082.7 5082.7 82606.6 84689.0 87143.6 90480.0 97324.5 68083.3 70601.0 70969.6 71753.6 82221.0 71950.0 73657.9 73811.8 74992.3 75241.0 10497.7 11048.3 11049.3 11087.4 11264.9 47975.3 48473.5 50559.5 50777.0 51285.2 54366.0 62254.0 62860.7 63121.7 63318.7 12356.6 12540.5 12866.0 12918.0 13115.1 13876.7 13941.7 14211.1 14490.6 15103.9 13001.1 13794.4 13817.0 13823.5 14062.0 13411.5 13570.4 13613.7 13739.9 13855.7 12597.1 13432.4 13492.9 13546.7 13685.0 9112.5 9611.5 9659.1 9673.8 9784.8 11049.4 11117.7 11388.4 11401.4 11410.1 02:37:05 INFO - PROCESS | 1641 | -------- Summary: end -------- 02:37:05 INFO - PROCESS | 1641 | 02:37:06 INFO - TEST-INFO | 1641: exit 0 02:37:06 INFO - TEST-OK | dromaeo_css | took 1120335ms 02:37:06 INFO - TEST-START | kraken 02:37:06 INFO - operating with platform_type : mac_ 02:37:06 INFO - Initialising browser for kraken test... 02:37:06 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUqUgjQ/profile http://localhost:49240/getInfo.html) 02:37:08 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 02:37:08 INFO - PROCESS | 1647 | colorDepth:24 02:37:08 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 02:37:08 INFO - PROCESS | 1647 | __metrics 02:37:08 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:37:08 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:37:09 INFO - TEST-INFO | 1647: exit 0 02:37:09 INFO - Browser initialized. 02:37:09 INFO - Running cycle 1/1 for kraken test... 02:37:09 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpUqUgjQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:37:21 INFO - PROCESS | 1649 | RSS: Main: 214724608 02:37:21 INFO - PROCESS | 1649 | 02:37:24 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 02:37:25 INFO - PROCESS | 1649 | RSS: Main: 267829248 02:37:25 INFO - PROCESS | 1649 | 02:37:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 02:37:28 INFO - PROCESS | 1649 | RSS: Main: 278433792 02:37:28 INFO - PROCESS | 1649 | 02:37:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 02:37:32 INFO - PROCESS | 1649 | RSS: Main: 375226368 02:37:32 INFO - PROCESS | 1649 | 02:37:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 02:37:34 INFO - PROCESS | 1649 | RSS: Main: 278413312 02:37:34 INFO - PROCESS | 1649 | 02:37:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:37:37 INFO - PROCESS | 1649 | RSS: Main: 280047616 02:37:37 INFO - PROCESS | 1649 | 02:37:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 02:37:41 INFO - PROCESS | 1649 | RSS: Main: 319049728 02:37:41 INFO - PROCESS | 1649 | 02:37:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 02:37:44 INFO - PROCESS | 1649 | RSS: Main: 321597440 02:37:44 INFO - PROCESS | 1649 | 02:37:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 02:37:48 INFO - PROCESS | 1649 | RSS: Main: 316837888 02:37:48 INFO - PROCESS | 1649 | 02:37:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:37:50 INFO - PROCESS | 1649 | RSS: Main: 295358464 02:37:50 INFO - PROCESS | 1649 | 02:37:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:37:53 INFO - PROCESS | 1649 | RSS: Main: 296894464 02:37:53 INFO - PROCESS | 1649 | 02:37:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:37:56 INFO - PROCESS | 1649 | RSS: Main: 303247360 02:37:56 INFO - PROCESS | 1649 | 02:37:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:37:59 INFO - PROCESS | 1649 | RSS: Main: 320327680 02:37:59 INFO - PROCESS | 1649 | 02:38:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:38:03 INFO - PROCESS | 1649 | RSS: Main: 314306560 02:38:03 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 02:38:05 INFO - PROCESS | 1649 | RSS: Main: 359305216 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | __start_tp_report 02:38:05 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 02:38:05 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 02:38:05 INFO - PROCESS | 1649 | |i|pagename|runs| 02:38:05 INFO - PROCESS | 1649 | |0;ai-astar;121;169;162;115;116;117;117;115;116;116 02:38:05 INFO - PROCESS | 1649 | |1;audio-beat-detection;141;109;107;145;110;109;108;109;125;108 02:38:05 INFO - PROCESS | 1649 | |2;audio-dft;187;187;183;185;185;185;185;185;183;188 02:38:05 INFO - PROCESS | 1649 | |3;audio-fft;81;58;59;91;63;63;62;60;78;60 02:38:05 INFO - PROCESS | 1649 | |4;audio-oscillator;86;70;88;69;69;68;83;69;68;83 02:38:05 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;124;108;87;87;87;87;91;135;86;86 02:38:05 INFO - PROCESS | 1649 | |6;imaging-darkroom;94;94;94;95;93;95;94;94;94;95 02:38:05 INFO - PROCESS | 1649 | |7;imaging-desaturate;120;136;122;84;85;86;102;135;129;84 02:38:05 INFO - PROCESS | 1649 | |8;json-parse-financial;64;62;62;66;65;68;65;64;66;64 02:38:05 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;57;57;59;67;60;58;59;58;61;84 02:38:05 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;83;85;83;83;82;83;84;84;90;86 02:38:05 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;134;137;146;136;136;138;141;166;199;152 02:38:05 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;150;167;165;166;164;167;164;166;166;168 02:38:05 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;66;69;67;64;65;64;64;64;64;63 02:38:05 INFO - PROCESS | 1649 | __end_tp_report 02:38:05 INFO - PROCESS | 1649 | __start_cc_report 02:38:05 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2149 02:38:05 INFO - PROCESS | 1649 | __end_cc_report 02:38:05 INFO - PROCESS | 1649 | __startTimestamp1491644285284__endTimestamp 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | ------- Summary: start ------- 02:38:05 INFO - PROCESS | 1649 | Number of tests: 14 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:126.40 Median:117.00 stddev:20.74 (17.7%) stddev-sans-first:21.91 02:38:05 INFO - PROCESS | 1649 | Values: 121.0 169.0 162.0 115.0 116.0 117.0 117.0 115.0 116.0 116.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:109.50 stddev:14.63 (13.4%) stddev-sans-first:12.71 02:38:05 INFO - PROCESS | 1649 | Values: 141.0 109.0 107.0 145.0 110.0 109.0 108.0 109.0 125.0 108.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:185.30 Median:185.00 stddev:1.64 (0.9%) stddev-sans-first:1.62 02:38:05 INFO - PROCESS | 1649 | Values: 187.0 187.0 183.0 185.0 185.0 185.0 185.0 185.0 183.0 188.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:67.50 Median:63.00 stddev:11.50 (18.3%) stddev-sans-first:11.11 02:38:05 INFO - PROCESS | 1649 | Values: 81.0 58.0 59.0 91.0 63.0 63.0 62.0 60.0 78.0 60.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:76.50 stddev:8.49 (11.1%) stddev-sans-first:8.07 02:38:05 INFO - PROCESS | 1649 | Values: 86.0 70.0 88.0 69.0 69.0 68.0 83.0 69.0 68.0 83.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:87.00 stddev:18.15 (20.9%) stddev-sans-first:16.59 02:38:05 INFO - PROCESS | 1649 | Values: 124.0 108.0 87.0 87.0 87.0 87.0 91.0 135.0 86.0 86.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 02:38:05 INFO - PROCESS | 1649 | Values: 94.0 94.0 94.0 95.0 93.0 95.0 94.0 94.0 94.0 95.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:108.30 Median:110.00 stddev:22.35 (20.3%) stddev-sans-first:23.30 02:38:05 INFO - PROCESS | 1649 | Values: 120.0 136.0 122.0 84.0 85.0 86.0 102.0 135.0 129.0 84.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:1.84 (2.8%) stddev-sans-first:1.94 02:38:05 INFO - PROCESS | 1649 | Values: 64.0 62.0 62.0 66.0 65.0 68.0 65.0 64.0 66.0 64.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:59.50 stddev:8.26 (13.9%) stddev-sans-first:8.56 02:38:05 INFO - PROCESS | 1649 | Values: 57.0 57.0 59.0 67.0 60.0 58.0 59.0 58.0 61.0 84.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.00 stddev:2.31 (2.8%) stddev-sans-first:2.40 02:38:05 INFO - PROCESS | 1649 | Values: 83.0 85.0 83.0 83.0 82.0 83.0 84.0 84.0 90.0 86.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:143.50 stddev:20.27 (14.1%) stddev-sans-first:20.81 02:38:05 INFO - PROCESS | 1649 | Values: 134.0 137.0 146.0 136.0 136.0 138.0 141.0 166.0 199.0 152.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.30 Median:166.00 stddev:5.19 (3.1%) stddev-sans-first:1.36 02:38:05 INFO - PROCESS | 1649 | Values: 150.0 167.0 165.0 166.0 164.0 167.0 164.0 166.0 166.0 168.0 02:38:05 INFO - PROCESS | 1649 | 02:38:05 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:64.50 stddev:1.83 (2.8%) stddev-sans-first:1.90 02:38:05 INFO - PROCESS | 1649 | Values: 66.0 69.0 67.0 64.0 65.0 64.0 64.0 64.0 64.0 63.0 02:38:05 INFO - PROCESS | 1649 | -------- Summary: end -------- 02:38:05 INFO - PROCESS | 1649 | 02:38:06 INFO - TEST-INFO | 1649: exit 0 02:38:06 INFO - TEST-OK | kraken | took 60228ms 02:38:06 INFO - SUITE-END | took 1180s 02:38:06 INFO - Completed test suite (00:19:40) 02:38:06 INFO - javascript benchmark 02:38:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7579.327521441117, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3040.2095455181648, 3362.428387174181, 3364.7308215342773, 3391.287829555793, 3424.589049314081, 5905.468507834294, 5963.3738457462, 5973.253343332083, 5975.223220981266, 5999.340072592011, 5939.732712027958, 6010.038393856973, 6012.669303188323, 6025.668588227646, 6037.667928263943, 5340.359156901177, 5384.165454354581, 5400.972995135038, 5441.673499590018, 5460.863478413032, 5293.04725149473, 5293.735313234342, 5339.919901201466, 5488.902219556105, 5535.197396377523, 5351.277577527023, 5382.757775900094, 5385.299911011563, 5396.19057141428, 5404.405515393323, 5360.544353729938, 5367.087595108841, 5370.087085195487, 5372.57019438444, 5378.731063446812, 5207.0, 5322.308099947021, 5322.308099947021, 5326.414094449608, 5353.812616558441, 5884.617499862496, 5913.852153696149, 5931.347551769302, 5934.347221805598, 5962.791302304398, 6158.291796443376, 6212.65830379329, 6215.471684906788, 6256.937430625727, 6262.060690896356, 6302.842428939313, 6333.588316759442, 6339.239291285029, 6341.524385671093, 6342.936570634327, 4948.331975183376, 5992.43071908168, 6026.005709058, 6045.516358691294, 6049.183360246355, 6206.689665516707, 6229.626222426669, 6284.962981108158, 6347.873042539123, 6401.167848179707, 6337.841553961096, 6342.763161183617, 6352.650604216812, 6361.0, 6366.522510811707, 6323.747050117944, 6326.49388048955, 6327.272363678236, 6331.923572992602, 6346.555741098171, 6166.1983942087245, 6232.657203853832, 6287.49700023997, 6291.905621415683, 6314.0, 6219.564630475823, 6223.408776166257, 6272.153259309935, 6307.936920630735, 6318.71565779541, 5152.098382783073, 5159.2003239497935, 5162.277281180638, 5164.380274367025, 5186.170212765939, 4392.604665580113, 4503.864884053483, 4514.525974772667, 4548.909021819545, 4556.270996640522, 5843.707814609253, 5857.4435428634215, 5914.0537513998415, 5947.078202878561, 5969.134475501115, 6316.084167795644, 6321.1150438938585, 6321.683915804191, 6331.68341582919, 6337.112804207414, 5951.851203719942, 5957.285125784933, 5968.22413086296, 5970.223870896755, 5986.5210783137245, 5417.83746487606, 5435.701036442954, 5500.4499550044675, 5576.386597474275, 5641.689707066069, 6553.836154096186, 6579.736810527621, 6600.735970561123, 6604.3725846044545, 6610.173728283965, 6357.078223657636, 6360.395762402564, 6360.745570177141, 6361.968190159019, 6370.076338930737, 6260.311365749855, 6524.314946930694, 6615.735370585123, 6619.503537234823, 6637.4690024798865, 6217.347178546188, 6230.314665386894, 6231.875362492816, 6237.376262373727, 6251.6561589113035, 5695.71846334588, 5975.880482390415, 6308.873822523432, 6309.7160627770945, 6336.493080553452, 6242.625442473466, 6253.0, 6293.55945083858, 6306.716197771019, 6310.6213627182515, 6278.0269058296035, 6298.275698294755, 6306.990881458944, 6309.526785491014, 6311.337309582428, 5325.748449114532, 5824.679642619697, 6133.355997620186, 6143.354947730424, 6143.784967526115, 6114.723190878713, 6144.832481828438, 6229.595076320024, 6310.716017779303, 6334.524910631629, 6138.447539721446, 6139.539534534839, 6141.140240366263, 6144.38556144382, 6167.599106058092], "value": 5836.195534386971, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [754.2721273970619, 867.4925168776264, 881.5107615273521, 884.1158841158837, 963.2534785541209, 30773.38453230937, 37109.443358349614, 38828.835134946, 38842.61157388425, 38851.028724281874, 37439.43840842389, 37501.0, 37581.0, 37599.62400376002, 37626.62373376265, 7331.486795924261, 7507.474476786627, 7717.884231736529, 7873.133955264916, 7876.921230787676, 10897.727556811104, 11146.442677866115, 11162.832557511643, 11193.832092518578, 11269.718257043598, 11052.281601695864, 11120.833187502194, 11126.0, 11129.443527823616, 11132.220744547885, 7291.744788932362, 7476.252374762535, 11064.225504214708, 11101.44492775366, 11112.222144449892, 7302.1967583565765, 7344.963275183643, 7348.1182258129365, 10651.627193048284, 10940.0, 38273.23453530914, 38483.0, 38526.614733852584, 38537.14628537152, 38598.22803543913, 3770.4909837171904, 3775.735698501106, 3776.8111594420443, 3801.3293723732777, 3957.129431525089, 22839.885800571057, 26560.468790624273, 27039.594406083925, 28214.576781348296, 28276.293092672848, 3052.4047810676925, 3055.4500189965797, 3090.428270769892, 3091.3971775503787, 3208.470602350609, 264196.03705944616, 318122.0, 318599.0, 320855.0, 321026.0, 40493.59506404957, 40651.79674101611, 41944.370834437505, 43502.34746478805, 50790.49209507932, 43627.56372436298, 43854.12291754147, 43882.56117438849, 43944.0, 51941.48058519442, 14524.564263072125, 14603.561893143222, 14615.415383384758, 14626.48807291761, 14634.560963171121, 12636.178648387826, 12768.0, 12797.296148711725, 12815.743685126432, 12821.23072615646, 11473.827892581618, 11543.249688770204, 11597.362145081932, 11650.41747912601, 11653.242539234923, 5447.4552544746, 5552.500274975271, 5553.666779993213, 5569.136783798518, 5589.329280486368, 931.9487428191379, 951.9857202141974, 957.6504575829912, 962.6630679262203, 981.4444106091781, 26276.0, 27000.0, 27409.0, 27429.90280388802, 27643.723562764517, 1044.3942513342242, 1045.8431235314763, 1056.1339701444895, 1065.3075500924306, 1076.3380520979697, 11948.701282468008, 13669.111507751964, 14078.10084285069, 14288.57134285973, 14480.493182738554, 362117.0, 363019.0, 363775.0, 365463.0, 367875.0, 53163.73418132962, 53455.46544534505, 53488.19767703488, 53556.196657050175, 53659.73170134125, 1417.8369487508862, 1476.8153980752406, 1477.4385733421232, 1479.3416929466284, 1480.2598700649676, 24739.84425375754, 26401.735982639926, 26711.86644066845, 26855.731442685323, 26871.77538695369, 38012.429813553375, 43611.9097022577, 43650.56349436465, 43719.125617488105, 43745.78127109344, 380301.0, 381215.0, 381686.0915695404, 382055.0, 382739.0863045667, 27209.0, 27694.44611107807, 28575.714242857306, 31946.520802187522, 32815.0, 34092.488612670335, 42188.78905605452, 42788.78605606952, 42858.928526785836, 46093.53906460892, 28454.43091138124, 28987.56518652264, 29603.407931841673, 30211.697883021767, 30276.54585181268, 30763.846180768953, 30829.0, 30848.0, 30907.0, 30935.834491586273, 27032.45935081248, 35258.0, 35689.82155089208, 35925.1018724534, 36198.09504762297, 27469.17592472229, 28466.430671386872, 29262.17480964511, 29373.559396609497, 29497.85251073731, 22659.886700566392, 22686.0, 23011.0, 23059.538809223384, 23067.538649226586], "value": 17774.085518940457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.6090865948996, 427.9978600106999, 429.7932281125155, 434.55675211284483, 439.63071020342903, 18931.99999999998, 19864.205431782728, 21243.831589262583, 21462.248821291258, 21522.56954860906, 20874.582508349824, 21109.944502774837, 22688.546229075408, 22964.0, 22998.88500557495, 6210.223722034746, 6754.0, 6761.594304341731, 6763.8309042274095, 6777.728890844361, 6316.399942005502, 6558.704858281365, 6619.338066193366, 6619.867602647944, 6621.768238111667, 6389.265234498046, 6621.172353455818, 6626.469882409396, 6629.2376376716575, 6644.867102657944, 5717.342505611846, 6586.571872828251, 6601.768938087192, 6692.665366731644, 6693.698783554752, 6350.110984462179, 6427.132337134521, 6451.7741879034475, 6522.119513865615, 6523.706433210469, 25440.618390724154, 25480.872595637087, 26296.0, 26488.94044238228, 26771.73228267712, 3289.96710032899, 3332.7500437467043, 3404.14896275931, 3408.744344174197, 3541.3448512025357, 15364.462243821412, 15694.372225110981, 15707.293171807296, 15714.842851571453, 15727.13500757458, 1988.8906110163934, 2051.753789545249, 2059.083707750052, 2110.145391116585, 2110.6939493773316, 97574.02425975792, 98494.0, 98822.0, 147021.0, 155684.0, 17172.828271717124, 18908.054597270082, 19550.608987820164, 19941.800581994285, 20457.38627841167, 32721.854735084158, 33118.50322245168, 33348.66651333504, 33397.165070872936, 33413.83293083519, 9930.0, 10228.335158214692, 10420.0, 10424.5830166794, 10608.0, 8615.267702245254, 8619.741407757778, 8626.482411055356, 8778.60496277669, 8801.119888011275, 9278.768030799283, 9343.159115679622, 9487.241020718326, 9519.952400238093, 9533.237341012702, 3899.512560929884, 3975.264576053439, 3995.680345572347, 4004.0, 4014.417909403179, 947.4552132523827, 988.5798535622332, 991.4249735153594, 1019.6176433837311, 1028.3161697471248, 15136.470223542123, 21072.578548428944, 21577.67633485499, 21622.35132946014, 21639.563555049852, 998.4957596413765, 1017.4505766885799, 1018.1600179851652, 1030.4281123976266, 1030.7577719235974, 10681.679549613524, 11075.566574361974, 11106.500207490682, 11172.547568816002, 11180.832287515694, 677401.0, 682645.0, 687656.0, 692244.0, 693352.0, 91940.54029729942, 94349.0, 122569.0, 152077.0, 156300.0, 1165.1960147498198, 1179.6696924861053, 1222.2727477151082, 1227.183922691418, 1279.2836011833404, 20037.599248014667, 20282.492937676085, 20875.851828149596, 20964.790352096283, 21028.26401075955, 62917.0, 63409.2931636155, 63782.3621763795, 65293.367665808735, 65388.0, 89441.55279223563, 89506.55246723724, 91216.08783912077, 92277.07722922953, 92554.0, 9870.802583948425, 11388.886111138783, 11497.08023358146, 11537.250078744855, 11905.642830715093, 10704.946475267574, 10706.304090234109, 13073.673158171121, 13316.800247996482, 13473.528426505418, 10937.453127343442, 11069.612563560237, 11284.887151128383, 11353.716157096138, 11523.0, 10478.842817357594, 10576.947115264375, 10583.0, 10925.453727313761, 10927.453627318444, 11263.718407039889, 11441.771164576496, 11461.885381146416, 11644.708882278012, 11699.707507312385, 11506.424678765861, 11800.76398472043, 11821.40892955366, 11928.343941083323, 11933.940330298641, 9286.442813431217, 11078.889211107786, 11228.473593441737, 11231.943840280746, 11384.0], "value": 11418.196143781548, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1519.3998370643603, 1545.235108621232, 1569.788078609388, 1570.246281784743, 1595.5771720494072, 10486.265961382696, 11024.118070554347, 11031.944840275808, 11040.779184416308, 11058.668239952804, 10700.678979630624, 10769.784604307928, 11074.280171788829, 11124.0, 11141.0, 1948.9805101948975, 2437.987810060947, 2440.2923152285816, 2444.963325550118, 2446.9143579974702, 2067.782882797307, 2328.4178955261186, 2416.299273210767, 2438.1953955194745, 2451.779339859412, 2355.8350915435926, 2412.8311018228733, 2424.6726691896542, 2427.1383658801137, 2427.4780922101745, 2320.88395580222, 2367.277980216042, 2370.7866292033714, 2375.845570037942, 2411.843230190032, 2377.2986968844234, 2393.8563686178886, 2402.423418379594, 2408.843425177358, 2409.0845478717974, 10927.836082458769, 11232.382218977953, 11233.932776386231, 11242.606508772154, 11244.100471962305, 5416.91874621881, 5467.289252397202, 5758.3665796762325, 5903.793367232126, 5920.5559583031445, 3458.948115778265, 3561.5013898054294, 3601.837917293702, 3636.8727094551564, 3645.580758212813, 1176.9823452648218, 1185.158537438411, 1216.7140721930339, 1229.8893099621077, 1234.888860002595, 12588.937055314756, 13233.139845909986, 13573.321333933263, 13867.098638588359, 14937.477188298357, 4503.707259028152, 4672.509386514367, 4691.3432119503295, 4703.0, 4707.152712511713, 11344.886551134383, 11376.65870023884, 11382.601608943647, 11383.14626403023, 11390.633124025004, 4845.854624361275, 5048.040872234264, 5060.3168572242275, 5065.518775716301, 5078.822241221539, 4444.977775111169, 5015.924761128586, 5262.026525092793, 5292.52367286946, 5292.655977361459, 3776.3580191367532, 3783.905402364963, 3862.4206369044305, 3868.458415821787, 3870.767753934773, 1023.1303392116641, 1025.1035584169226, 1034.2088302448633, 1034.8602938603342, 1037.9636712715019, 900.4912224593215, 912.9520104791003, 923.3721069672687, 931.7437704631145, 969.2585172343163, 6410.391012853772, 6416.454601358937, 6609.900851487231, 6611.669416529155, 6714.597124172467, 943.1417410156724, 950.3870003847479, 951.9333646644668, 965.6571916969375, 1006.1799633298838, 3844.9250184708094, 4152.979235103805, 4188.81150348235, 4282.379055037064, 4299.0, 11482.94258528702, 13351.532696355775, 13494.662633434047, 13510.256935868425, 13520.661983450493, 13069.019823513085, 13309.73380532384, 13444.731105377838, 13487.055906086676, 13488.5279015234, 9435.811283774423, 9918.851217231748, 9933.552990115462, 10028.598856045823, 10166.0, 6380.298167201511, 6431.292557818542, 6509.218893732783, 6555.508336874658, 6606.0, 11652.766944661229, 12054.0, 12067.57763478274, 12100.636980890587, 12194.329311887932, 10648.946755266174, 10833.945830270799, 12225.69435764113, 12236.816447753468, 12256.877431225574, 8958.417702849294, 9023.684171053981, 9063.954680226556, 9196.724098277062, 9269.397489163182, 10026.548805303924, 10244.795104098026, 10248.948755256177, 10252.743681408025, 10268.691939241835, 8490.533020683675, 8618.181272779077, 8722.738317850475, 8741.91258087411, 8745.606447709995, 8455.112213217524, 8488.65709782781, 8488.70289539888, 8531.573421329032, 8609.139086091342, 6547.70535325902, 7874.527528348317, 7883.172266912121, 7883.763487095396, 7907.762767116996, 5723.484886360247, 5724.484796368347, 5745.3105627324985, 5919.733611987557, 5936.614120082181, 5763.4812866842185, 5800.361960184461, 5961.552883533665, 5991.191189189578, 6135.355787642234], "value": 5039.600582390403, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.0884250625767, 374.36171327885955, 386.497553180865, 388.70458451576803, 388.9611038896109, 4430.867073987782, 4497.752623605708, 5035.949640503594, 5200.8699782505455, 5208.088584497712, 4404.4494438195225, 4949.727764972934, 5031.748412579365, 5031.924521132177, 5155.968806238758, 4394.560543945612, 4407.537208593099, 4411.889702757425, 4434.357018232355, 4444.377787109807, 3931.7051221158526, 3962.3264045112255, 4165.541790403053, 4385.0, 4452.755098469583, 3931.1720050176664, 4379.715318504303, 4382.846600368987, 4408.97795511022, 4410.448693913261, 3863.420486926955, 4201.432806571104, 4278.5184592936175, 4413.940654242959, 4429.13631841791, 4033.743625900548, 4141.716068018924, 4259.467566554181, 4309.547497512747, 4311.378442108644, 4025.3156963316305, 4131.793410329502, 4539.500654927955, 4849.787553111722, 4943.577646953003, 4424.960134368421, 4608.612669687776, 4621.399218101626, 4737.360456338385, 5155.066849931276, 5038.773255203525, 5259.421463639035, 5460.290162278879, 5474.534664553517, 5521.861953451196, 4507.588440184486, 4723.7245943595035, 4743.7429081293485, 4743.786529606176, 4792.353032340624, 4767.809287628525, 5143.485651434864, 5720.370759216483, 5763.740631671585, 5815.360310365899, 4398.428204333481, 4491.3487544306545, 4621.884452888705, 4925.975370123127, 4945.36802855057, 4475.060237350193, 4496.5503449655425, 4654.138344662095, 4709.693869898514, 5001.524855138756, 4383.802728877208, 4612.310151878551, 5136.306598609214, 5137.486251374833, 5227.372715274191, 4643.698159619614, 4879.56083952446, 5090.567301779391, 5346.4653534647005, 5390.598444404404, 1520.217088199578, 1607.710612089835, 1635.1905806625796, 1704.3267909175806, 1706.7354560043213, 1340.892728581711, 1376.9173849569058, 1394.5398018654023, 1396.413506327345, 1423.6939058102557, 4668.52941323482, 4928.704277743347, 5078.847634570969, 5107.552402128548, 5131.307273518102, 5010.799568017239, 5100.38795344561, 5234.555062819627, 5307.867303317448, 5344.545713614386, 4626.421697287839, 4705.85882423521, 4773.928391074067, 5038.715127642501, 5081.720505372166, 2417.9844465324604, 2524.863811284929, 2651.4166883286034, 2911.3740545782757, 2943.983371390604, 5551.944480555224, 5690.614084619645, 5914.201582786355, 5929.970350148222, 5949.9107513387335, 5139.948600513947, 5585.3297604287745, 5719.456651618089, 5844.532437404998, 5874.236349274567, 5319.951818549809, 5426.035177812258, 5442.319710036246, 5783.334916484574, 5944.740998420572, 4938.827141050046, 5429.945700542944, 5498.560115190695, 5654.0, 5675.120356344773, 4748.335233067304, 4849.612031037579, 5180.513353590373, 5385.946140538545, 5603.831885043455, 4983.476734942924, 5841.211436456024, 5853.619514731528, 5861.706914654165, 5864.413558644272, 3802.885913422602, 3876.5735769065946, 4137.586241375839, 4196.223698615766, 4458.308962110878, 4734.768960070338, 5485.556699073763, 5655.786439102894, 5676.801311954062, 5697.4872261495, 4509.413776209072, 4635.9304610431545, 5430.968116057937, 5434.266374039454, 5470.863228419162, 4639.211334073283, 5068.746562671926, 5268.81559145428, 5445.346558413015, 5509.228707980806, 4355.73865568067, 4914.557689807934, 5259.710715910662, 5287.973560132328, 5362.222477740784, 4785.665003449725, 5065.442801291929, 5442.809501667422, 5486.506214440719, 5513.61404701667, 5158.936425429858, 5191.065608190562, 5309.840704778863, 5325.760340784752, 5326.360836699466], "value": 4234.134912516604, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.4348065028628, 740.2560426771095, 775.0, 811.1847593168862, 811.7321283976287, 15074.698506029887, 15912.204389780523, 15944.681106377866, 15948.362065517395, 15969.121698306588, 14441.350139243734, 14443.205623690692, 14481.420743170287, 14484.782728259059, 14606.0, 893.2809088683607, 922.9769255768609, 930.3534043839534, 933.8645896345026, 934.1592566689999, 909.1090731083542, 912.8174365126968, 921.71426857674, 921.870938068671, 923.9214666753334, 962.4177372689527, 962.6486332488661, 963.6723514005237, 964.7973925475624, 966.7099870038995, 955.3599088610648, 955.9091886270793, 960.4477425480377, 961.0005594182043, 961.8076384723014, 723.7249845058844, 724.999500689051, 726.5822152262417, 726.9491135620456, 727.3344889426169, 13973.161610303414, 14216.431342746278, 14599.0, 15698.293576789072, 15721.842781572152, 13881.722365552734, 13938.442462301497, 14108.506202282872, 14130.92934535331, 14177.43290268388, 11625.70935726605, 11853.525858965631, 11887.699182193772, 11889.702757431047, 11922.821157682642, 3583.2475180212186, 3622.2212224371888, 3639.7452178347526, 3712.517372741553, 3762.435634654824, 96559.03440965571, 104593.0, 106086.46956765244, 107123.0, 107632.84734305348, 9237.261019118454, 10104.949475252577, 10605.522751476203, 10699.19007530346, 10775.299605525615, 21846.89076554607, 22406.103755849585, 22533.32400027969, 22555.774442255366, 22574.887125564266, 7782.49413788102, 7795.571243581544, 7798.0, 7901.920980790233, 7988.80027999293, 4265.55211702773, 4319.827206911688, 4426.159029784395, 4434.246178149722, 4465.553444655573, 2919.226405002676, 3516.6659167379057, 3526.1713497328105, 3527.894163175109, 3553.6091029986683, 614.8001899382718, 628.8207860759661, 645.9128017717642, 654.4633400611548, 655.6426747422666, 653.5033374635217, 663.3996233408773, 680.8672308899717, 684.3430306905327, 720.7333286683961, 9954.552045157985, 10386.0, 10441.791164176826, 10515.316504427188, 10569.894301057046, 681.9318068193141, 689.8482333886637, 696.8362434827811, 699.4858778797579, 708.840510885057, 4785.0, 5064.417591976896, 5073.518015788494, 5082.695038297714, 5082.720450375265, 82606.58696706478, 84689.0, 87143.56428217945, 90480.0, 97324.54013189807, 68083.3191668087, 70601.0, 70969.64515177494, 71753.6412317935, 82221.0, 71950.0, 73657.89513157307, 73811.7856464307, 74992.25007749852, 75241.0, 10497.685069447623, 11048.337099774039, 11049.28179668319, 11087.390193539433, 11264.887351126712, 47975.28037079516, 48473.5152648469, 50559.49440505548, 50777.0, 51285.230721539956, 54366.0, 62254.0, 62860.68569657122, 63121.68439157775, 63318.68340658267, 12356.62930112098, 12540.498380064695, 12866.0, 12918.0, 13115.081944264191, 13876.72246555043, 13941.651458713613, 14211.147331160164, 14490.637734056732, 15103.924480377527, 13001.108304294445, 13794.37925293344, 13817.0, 13823.51617693376, 14062.0, 13411.530596429078, 13570.38933248026, 13613.65965850822, 13739.931300343434, 13855.72288554203, 12597.067296669762, 13432.39554220043, 13492.93253533726, 13546.661333466742, 13685.0, 9112.469486176811, 9611.471369074488, 9659.130678238993, 9673.806523869624, 9784.804303914025, 11049.392283424168, 11117.722056948318, 11388.373639449579, 11401.429928503707, 11410.087193024705], "value": 7500.1321210396645, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1460.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 169.0, 162.0, 115.0, 116.0, 117.0, 117.0, 115.0, 116.0, 116.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 107.0, 145.0, 110.0, 109.0, 108.0, 109.0, 125.0, 108.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 183.0, 185.0, 185.0, 185.0, 185.0, 185.0, 183.0, 188.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 91.0, 63.0, 63.0, 62.0, 60.0, 78.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 88.0, 69.0, 69.0, 68.0, 83.0, 69.0, 68.0, 83.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 108.0, 87.0, 87.0, 87.0, 87.0, 91.0, 135.0, 86.0, 86.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 95.0, 94.0, 94.0, 94.0, 95.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 136.0, 122.0, 84.0, 85.0, 86.0, 102.0, 135.0, 129.0, 84.0], "value": 108.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 66.0, 65.0, 68.0, 65.0, 64.0, 66.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 59.0, 67.0, 60.0, 58.0, 59.0, 58.0, 61.0, 84.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 83.0, 83.0, 82.0, 83.0, 84.0, 84.0, 90.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 137.0, 146.0, 136.0, 136.0, 138.0, 141.0, 166.0, 199.0, 152.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 167.0, 165.0, 166.0, 164.0, 167.0, 164.0, 166.0, 166.0, 168.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 63.0], "value": 65.0, "unit": "score"}]}]} 02:38:06 INFO - Return code: 0 02:38:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:38:06 ERROR - # TBPL SUCCESS # 02:38:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:38:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:38:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:38:06 INFO - 2017-04-08 02:38:06.624 screenresolution[1653:11574] starting screenresolution argv=screenresolution get 02:38:06 INFO - 2017-04-08 02:38:06.626 screenresolution[1653:11574] Display 0: 1600x1200x32@60 02:38:06 INFO - 2017-04-08 02:38:06.633 screenresolution[1654:11577] starting screenresolution argv=screenresolution list 02:38:06 INFO - Available Modes on Display 0 02:38:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:38:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:38:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:38:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:38:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:38:06 INFO - Intel Iris: 02:38:06 INFO - Chipset Model: Intel Iris 02:38:06 INFO - Type: GPU 02:38:06 INFO - Bus: Built-In 02:38:06 INFO - VRAM (Dynamic, Max): 1536 MB 02:38:06 INFO - Vendor: Intel (0x8086) 02:38:06 INFO - Device ID: 0x0a2e 02:38:06 INFO - Revision ID: 0x0009 02:38:06 INFO - Displays: 02:38:06 INFO - Display: 02:38:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:38:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:38:06 INFO - Display Serial Number: 1600x1200 60 02:38:06 INFO - Main Display: Yes 02:38:06 INFO - Mirror: Off 02:38:06 INFO - Online: Yes 02:38:06 INFO - Rotation: Supported 02:38:06 INFO - Return code: 0 02:38:06 INFO - Running post-action listener: _resource_record_post_action 02:38:06 INFO - [mozharness: 2017-04-08 09:38:06.874661Z] Finished run-tests step (success) 02:38:06 INFO - Running post-run listener: _resource_record_post_run 02:38:06 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 34073600; Write bytes: 509282304; Read time: 311; Write time: 1673 02:38:06 INFO - TinderboxPrint: CPU usage
54.0% 02:38:06 INFO - TinderboxPrint: I/O read bytes / time
34,073,600 / 311 02:38:06 INFO - TinderboxPrint: I/O write bytes / time
509,282,304 / 1,673 02:38:06 INFO - TinderboxPrint: CPU idle
2,209.6 (46.0%) 02:38:06 INFO - TinderboxPrint: CPU system
964.3 (20.1%) 02:38:06 INFO - TinderboxPrint: CPU user
1,631.8 (34.0%) 02:38:06 INFO - TinderboxPrint: Swap in / out
237,948,928 / 0 02:38:06 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205920768; Write bytes: 199331840; Read time: 15750; Write time: 389 02:38:06 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31992832; Write bytes: 298887168; Read time: 287; Write time: 1248 02:38:07 INFO - Running post-run listener: _upload_blobber_files 02:38:07 INFO - Blob upload gear active. 02:38:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:38:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:38:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:38:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:38:07 INFO - (blobuploader) - INFO - Open directory for files ... 02:38:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:38:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:08 INFO - (blobuploader) - INFO - Done attempting. 02:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:38:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:09 INFO - (blobuploader) - INFO - Done attempting. 02:38:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:38:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:38:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:10 INFO - (blobuploader) - INFO - Done attempting. 02:38:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:38:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:38:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:12 INFO - (blobuploader) - INFO - Done attempting. 02:38:12 INFO - (blobuploader) - INFO - Iteration through files over. 02:38:12 INFO - Return code: 0 02:38:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:38:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:38:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fade88d5e1a6ec01e3374a4d674b3a9851068def4440f7f3fb03ff72983c86e5bdda8df3c0e3d7c0d5f30d5d89eb5a4115a638d46357b884de126ce7230bc3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f080624a61c6e0dc1095b9748d74301606b007fcff2687e4282b02922b6f053a5bff37ee3afe9fa10425ec58ef5811d0491aedd68e71716dfcba25cea1f0dde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f78d068f6b339ef75c3ca4215fb5552725e54a19939e29b8a00ac0f19af6a8e3444071c5f7e9843176570d5c4583293f7016b396b365f8431c38b6403b00ce0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc76b917b21da3af071fdeb2138946c88b8d14504f920912a2d3af277ae2a0aba806ef573d0f9bf0c3f62d580edb0b0673f41d2e1dc9f1ff73a89c79bba02728"} 02:38:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:38:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:38:12 INFO - Contents: 02:38:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fade88d5e1a6ec01e3374a4d674b3a9851068def4440f7f3fb03ff72983c86e5bdda8df3c0e3d7c0d5f30d5d89eb5a4115a638d46357b884de126ce7230bc3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f080624a61c6e0dc1095b9748d74301606b007fcff2687e4282b02922b6f053a5bff37ee3afe9fa10425ec58ef5811d0491aedd68e71716dfcba25cea1f0dde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f78d068f6b339ef75c3ca4215fb5552725e54a19939e29b8a00ac0f19af6a8e3444071c5f7e9843176570d5c4583293f7016b396b365f8431c38b6403b00ce0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc76b917b21da3af071fdeb2138946c88b8d14504f920912a2d3af277ae2a0aba806ef573d0f9bf0c3f62d580edb0b0673f41d2e1dc9f1ff73a89c79bba02728"} 02:38:12 INFO - Running post-run listener: copy_logs_to_upload_dir 02:38:12 INFO - Copying logs to upload dir... 02:38:12 INFO - mkdir: /builds/slave/test/build/upload/logs 02:38:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.634652 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-08 02:38:12.331400) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 02:38:12.335011) ========= 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.uakFQjnBZG/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.ShMhTHTxkD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/fade88d5e1a6ec01e3374a4d674b3a9851068def4440f7f3fb03ff72983c86e5bdda8df3c0e3d7c0d5f30d5d89eb5a4115a638d46357b884de126ce7230bc3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f080624a61c6e0dc1095b9748d74301606b007fcff2687e4282b02922b6f053a5bff37ee3afe9fa10425ec58ef5811d0491aedd68e71716dfcba25cea1f0dde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f78d068f6b339ef75c3ca4215fb5552725e54a19939e29b8a00ac0f19af6a8e3444071c5f7e9843176570d5c4583293f7016b396b365f8431c38b6403b00ce0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc76b917b21da3af071fdeb2138946c88b8d14504f920912a2d3af277ae2a0aba806ef573d0f9bf0c3f62d580edb0b0673f41d2e1dc9f1ff73a89c79bba02728"} build_url:https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011353 build_url: 'https://queue.taskcluster.net/v1/task/KpejQbQvR12ENmSGx9tzIg/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/fade88d5e1a6ec01e3374a4d674b3a9851068def4440f7f3fb03ff72983c86e5bdda8df3c0e3d7c0d5f30d5d89eb5a4115a638d46357b884de126ce7230bc3f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f080624a61c6e0dc1095b9748d74301606b007fcff2687e4282b02922b6f053a5bff37ee3afe9fa10425ec58ef5811d0491aedd68e71716dfcba25cea1f0dde4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4f78d068f6b339ef75c3ca4215fb5552725e54a19939e29b8a00ac0f19af6a8e3444071c5f7e9843176570d5c4583293f7016b396b365f8431c38b6403b00ce0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc76b917b21da3af071fdeb2138946c88b8d14504f920912a2d3af277ae2a0aba806ef573d0f9bf0c3f62d580edb0b0673f41d2e1dc9f1ff73a89c79bba02728"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 02:38:12.371668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 02:38:12.372067) ========= 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.uakFQjnBZG/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.ShMhTHTxkD/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005263 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 02:38:12.421608) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 02:38:12.421971) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 02:38:12.422314) ========= ========= Total master_lag: 0.20 =========