builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0188 starttime: 1483067224.66 results: success (0) buildid: 20161229175220 builduid: 01df8a8ff2704d678a3c0a8e52fc3e89 revision: 31516cfb0f4153cfddf65a9cf8fbdb8f88694922 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.662376) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.662857) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.663139) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.693282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.693698) ========= 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.gucLwZaERP/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.EpYcDMNlmG/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 --2016-12-29 19:07:04-- 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% 157M=0s 2016-12-29 19:07:04 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104840 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.825778) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.826132) ========= 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.gucLwZaERP/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.EpYcDMNlmG/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.026807 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:04.878161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 19:07:04.878586) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 31516cfb0f4153cfddf65a9cf8fbdb8f88694922 --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 31516cfb0f4153cfddf65a9cf8fbdb8f88694922 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gucLwZaERP/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.EpYcDMNlmG/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 2016-12-29 19:07:04,968 truncating revision to first 12 chars 2016-12-29 19:07:04,968 Setting DEBUG logging. 2016-12-29 19:07:04,968 attempt 1/10 2016-12-29 19:07:04,968 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/31516cfb0f41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 19:07:06,067 unpacking tar archive at: mozilla-aurora-31516cfb0f41/testing/mozharness/ program finished with exit code 0 elapsedTime=1.381580 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-29 19:07:06.287410) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:06.287791) ========= script_repo_revision: 31516cfb0f4153cfddf65a9cf8fbdb8f88694922 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:06.288196) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:06.288500) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 19:07:06.312013) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-29 19:07:06.312484) ========= /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.gucLwZaERP/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.EpYcDMNlmG/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:06 INFO - MultiFileLogger online at 20161229 19:07:06 in /builds/slave/test 19:07:06 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 19:07:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:07:06 INFO - {'append_to_log': False, 19:07:06 INFO - 'base_work_dir': '/builds/slave/test', 19:07:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:07:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:07:06 INFO - 'branch': 'Mozilla-Aurora', 19:07:06 INFO - 'buildbot_json_path': 'buildprops.json', 19:07:06 INFO - 'config_files': ('talos/mac_config.py',), 19:07:06 INFO - 'default_actions': ('clobber', 19:07:06 INFO - 'read-buildbot-config', 19:07:06 INFO - 'download-and-extract', 19:07:06 INFO - 'populate-webroot', 19:07:06 INFO - 'create-virtualenv', 19:07:06 INFO - 'install', 19:07:06 INFO - 'run-tests'), 19:07:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:07:06 INFO - 'download_minidump_stackwalk': True, 19:07:06 INFO - 'download_symbols': 'ondemand', 19:07:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:07:06 INFO - 'tooltool.py': '/tools/tooltool.py', 19:07:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:07:06 INFO - '/tools/misc-python/virtualenv.py')}, 19:07:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:07:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:07:06 INFO - 'installer_path': 'installer.exe', 19:07:06 INFO - 'log_level': 'info', 19:07:06 INFO - 'log_name': 'talos', 19:07:06 INFO - 'log_to_console': True, 19:07:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:07:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:07:06 INFO - 'opt_config_files': (), 19:07:06 INFO - 'pip_index': False, 19:07:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:07:06 INFO - 'cmd': ('bash', 19:07:06 INFO - '-c', 19:07:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:07:06 INFO - 'enabled': True, 19:07:06 INFO - 'halt_on_failure': False, 19:07:06 INFO - 'name': 'check_screen_resolution'},), 19:07:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:07:06 INFO - 'cmd': ('bash', 19:07:06 INFO - '-c', 19:07:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:07:06 INFO - 'enabled': True, 19:07:06 INFO - 'halt_on_failure': False, 19:07:06 INFO - 'name': 'check_screen_resolution'},), 19:07:06 INFO - 'run_cmd_checks_enabled': True, 19:07:06 INFO - 'sps_profile': False, 19:07:06 INFO - 'sps_profile_interval': 0, 19:07:06 INFO - 'suite': 'dromaeojs', 19:07:06 INFO - 'system_bits': '32', 19:07:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:07:06 INFO - 'title': 't-yosemite-r7-0188', 19:07:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:07:06 INFO - 'use_talos_json': True, 19:07:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:07:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:07:06 INFO - 'work_dir': 'build'} 19:07:06 INFO - [mozharness: 2016-12-30 03:07:06.514319Z] Running clobber step. 19:07:06 INFO - Running pre-action listener: _resource_record_pre_action 19:07:06 INFO - Running main action method: clobber 19:07:06 INFO - rmtree: /builds/slave/test/build 19:07:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:07:08 INFO - Running post-action listener: _resource_record_post_action 19:07:08 INFO - [mozharness: 2016-12-30 03:07:08.852772Z] Finished clobber step (success) 19:07:08 INFO - [mozharness: 2016-12-30 03:07:08.852902Z] Running read-buildbot-config step. 19:07:08 INFO - Running pre-action listener: _resource_record_pre_action 19:07:08 INFO - Running main action method: read_buildbot_config 19:07:08 INFO - Using buildbot properties: 19:07:08 INFO - { 19:07:08 INFO - "project": "", 19:07:08 INFO - "product": "firefox", 19:07:08 INFO - "script_repo_revision": "production", 19:07:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 19:07:08 INFO - "repository": "", 19:07:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 19:07:08 INFO - "buildid": "20161229175220", 19:07:08 INFO - "pgo_build": "False", 19:07:08 INFO - "basedir": "/builds/slave/test", 19:07:08 INFO - "buildnumber": 3, 19:07:08 INFO - "slavename": "t-yosemite-r7-0188", 19:07:08 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 19:07:08 INFO - "platform": "yosemite_r7", 19:07:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:07:08 INFO - "branch": "mozilla-aurora", 19:07:08 INFO - "repo_path": "releases/mozilla-aurora", 19:07:08 INFO - "revision": "31516cfb0f4153cfddf65a9cf8fbdb8f88694922", 19:07:08 INFO - "stage_platform": "macosx64", 19:07:08 INFO - "builduid": "01df8a8ff2704d678a3c0a8e52fc3e89", 19:07:08 INFO - "slavebuilddir": "test" 19:07:08 INFO - } 19:07:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 19:07:08 INFO - Running post-action listener: _resource_record_post_action 19:07:08 INFO - [mozharness: 2016-12-30 03:07:08.856255Z] Finished read-buildbot-config step (success) 19:07:08 INFO - [mozharness: 2016-12-30 03:07:08.856355Z] Running download-and-extract step. 19:07:08 INFO - Running pre-action listener: _resource_record_pre_action 19:07:08 INFO - Running main action method: download_and_extract 19:07:08 INFO - mkdir: /builds/slave/test/build/tests 19:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:08 INFO - trying https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 19:07:08 INFO - Downloading https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:07:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 19:07:10 INFO - Downloaded 1199 bytes. 19:07:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 19:07:10 INFO - Using the following test package requirements: 19:07:10 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:07:10 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 19:07:10 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 19:07:10 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'jsshell-mac.zip'], 19:07:10 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 19:07:10 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 19:07:10 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 19:07:10 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 19:07:10 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 19:07:10 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 19:07:10 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 19:07:10 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:07:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:07:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:07:10 INFO - Fetch https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:07:10 INFO - Http code: 200 19:07:10 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 19:07:10 INFO - x-amz-cf-id: DDiTmw9w4-erSxLtXhmxN5zNOtCjf7whyX8OP2lJGNZtbp6ftpi5lg== 19:07:10 INFO - x-amz-version-id: ImrhO00VUgegOz.qf5T8jG5YPSPUKtR7 19:07:11 INFO - Expected file size: 16268365 19:07:11 INFO - Obtained file size: 16268365 19:07:18 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:07:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 19:07:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:07:18 INFO - Fetch https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 19:07:18 INFO - Http code: 200 19:07:18 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 19:07:18 INFO - x-amz-cf-id: KHRMxlHDN4Dt4TEDm1_AxrKam6HIBkOvxh4Na5Y8_PS-YwrAcBEe7g== 19:07:18 INFO - x-amz-version-id: ImrhO00VUgegOz.qf5T8jG5YPSPUKtR7 19:07:19 INFO - Expected file size: 16268365 19:07:19 INFO - Obtained file size: 16268365 19:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 19:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 19:07:26 INFO - Http code: 200 19:07:26 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 19:07:26 INFO - x-amz-cf-id: uzffbFNs49jz1T8vOuNPFLlfXpfB_LlxcJDCycIgOHUTCHZce6pkow== 19:07:26 INFO - x-amz-version-id: EhXb39eUo1ADNeLHaWhAnCfnnbJH5Fdl 19:07:27 INFO - Expected file size: 14409038 19:07:27 INFO - Obtained file size: 14409038 19:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:27 INFO - trying https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:07:27 INFO - Downloading https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 19:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 19:07:30 INFO - Downloaded 109823940 bytes. 19:07:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:07:30 INFO - mkdir: /builds/slave/test/properties 19:07:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:07:30 INFO - Writing to file /builds/slave/test/properties/build_url 19:07:30 INFO - Contents: 19:07:30 INFO - build_url:https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 19:07:30 INFO - Running post-action listener: _resource_record_post_action 19:07:30 INFO - Running post-action listener: set_extra_try_arguments 19:07:30 INFO - [mozharness: 2016-12-30 03:07:30.875046Z] Finished download-and-extract step (success) 19:07:30 INFO - [mozharness: 2016-12-30 03:07:30.875165Z] Running populate-webroot step. 19:07:30 INFO - Running pre-action listener: _resource_record_pre_action 19:07:30 INFO - Running main action method: populate_webroot 19:07:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:07:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:07:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:07:30 INFO - '--tppagecycles', 19:07:30 INFO - '7'], 19:07:30 INFO - 'tests': ['tsvgm']}}, 19:07:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:07:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:07:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:07:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:07:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['damp', 'tps']}, 19:07:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'tests': ['damp', 'tps']}, 19:07:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['dromaeo_dom']}, 19:07:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:07:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['basic_compositor_video']}, 19:07:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:07:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other-e10s': {'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['a11yr', 19:07:30 INFO - 'ts_paint', 19:07:30 INFO - 'tpaint', 19:07:30 INFO - 'sessionrestore', 19:07:30 INFO - 'sessionrestore_no_auto_restore', 19:07:30 INFO - 'tabpaint']}, 19:07:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tsvgx', 19:07:30 INFO - 'tsvgr_opacity', 19:07:30 INFO - 'tart', 19:07:30 INFO - 'tscrollx', 19:07:30 INFO - 'cart']}, 19:07:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:07:30 INFO - 'tsvgr_opacity', 19:07:30 INFO - 'tart', 19:07:30 INFO - 'tscrollx', 19:07:30 INFO - 'cart']}, 19:07:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s'], 19:07:30 INFO - 'tests': ['tp5o']}, 19:07:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'tests': ['tp5o']}, 19:07:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--disable-e10s', 19:07:30 INFO - '--xperf_path', 19:07:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:30 INFO - 'tests': ['tp5n']}, 19:07:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:30 INFO - 'talos_options': ['--xperf_path', 19:07:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:30 INFO - 'tests': ['tp5n']}}, 19:07:30 INFO - 'talos.zip': {'path': '', 19:07:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:07:30 INFO - Running post-action listener: _resource_record_post_action 19:07:30 INFO - [mozharness: 2016-12-30 03:07:30.894153Z] Finished populate-webroot step (success) 19:07:30 INFO - [mozharness: 2016-12-30 03:07:30.894261Z] Running create-virtualenv step. 19:07:30 INFO - Running pre-action listener: _resource_record_pre_action 19:07:30 INFO - Running main action method: create_virtualenv 19:07:30 INFO - Creating virtualenv /builds/slave/test/build/venv 19:07:30 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 19:07:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:07:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:07:31 INFO - Using real prefix '/tools/python27' 19:07:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:07:31 INFO - Installing distribute.............................................................................................................................................................................................done. 19:07:34 INFO - Installing pip.................done. 19:07:34 INFO - Return code: 0 19:07:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:07:34 INFO - Reading from file tmpfile_stdout 19:07:34 INFO - Output received: 19:07:34 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) 19:07:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:34 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:34 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 19:07:34 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 19:07:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:34 INFO - 'HOME': '/Users/cltbld', 19:07:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:34 INFO - 'LOGNAME': 'cltbld', 19:07:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:34 INFO - 'MOZ_NO_REMOTE': '1', 19:07:34 INFO - 'NO_EM_RESTART': '1', 19:07:34 INFO - 'PAGER': '/bin/cat', 19:07:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:34 INFO - 'PWD': '/builds/slave/test', 19:07:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:34 INFO - 'SHELL': '/bin/bash', 19:07:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:34 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:34 INFO - 'USER': 'cltbld', 19:07:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:34 INFO - 'XPC_FLAGS': '0x0', 19:07:34 INFO - 'XPC_SERVICE_NAME': '0', 19:07:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:35 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 19:07:35 INFO - Cleaning up... 19:07:35 INFO - Return code: 0 19:07:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:35 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 19:07:35 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 19:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:35 INFO - 'HOME': '/Users/cltbld', 19:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:35 INFO - 'LOGNAME': 'cltbld', 19:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:35 INFO - 'MOZ_NO_REMOTE': '1', 19:07:35 INFO - 'NO_EM_RESTART': '1', 19:07:35 INFO - 'PAGER': '/bin/cat', 19:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:35 INFO - 'PWD': '/builds/slave/test', 19:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:35 INFO - 'SHELL': '/bin/bash', 19:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:35 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:35 INFO - 'USER': 'cltbld', 19:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:35 INFO - 'XPC_FLAGS': '0x0', 19:07:35 INFO - 'XPC_SERVICE_NAME': '0', 19:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:35 INFO - Downloading/unpacking psutil>=3.1.1 19:07:35 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 19:07:35 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 19:07:35 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 19:07:35 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 19:07:35 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 19:07:35 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 19:07:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:07:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:38 INFO - Installing collected packages: psutil 19:07:38 INFO - Running setup.py install for psutil 19:07:39 INFO - building 'psutil._psutil_osx' extension 19:07:39 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 19:07:39 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 19:07:39 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 19:07:39 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 19:07:39 INFO - building 'psutil._psutil_posix' extension 19:07:39 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 19:07:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:07:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:07:39 INFO - ^ 19:07:39 INFO - 1 warning generated. 19:07:39 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 19:07:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:40 INFO - Successfully installed psutil 19:07:40 INFO - Cleaning up... 19:07:40 INFO - Return code: 0 19:07:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:40 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:40 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 19:07:40 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 19:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:40 INFO - 'HOME': '/Users/cltbld', 19:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:40 INFO - 'LOGNAME': 'cltbld', 19:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:40 INFO - 'MOZ_NO_REMOTE': '1', 19:07:40 INFO - 'NO_EM_RESTART': '1', 19:07:40 INFO - 'PAGER': '/bin/cat', 19:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:40 INFO - 'PWD': '/builds/slave/test', 19:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:40 INFO - 'SHELL': '/bin/bash', 19:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:40 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:40 INFO - 'USER': 'cltbld', 19:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:40 INFO - 'XPC_FLAGS': '0x0', 19:07:40 INFO - 'XPC_SERVICE_NAME': '0', 19:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:07:40 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 19:07:40 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 19:07:40 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 19:07:40 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 19:07:40 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 19:07:40 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 19:07:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:07:43 INFO - Installing collected packages: mozsystemmonitor 19:07:43 INFO - Running setup.py install for mozsystemmonitor 19:07:43 INFO - Successfully installed mozsystemmonitor 19:07:43 INFO - Cleaning up... 19:07:43 INFO - Return code: 0 19:07:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:43 INFO - 'HOME': '/Users/cltbld', 19:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:43 INFO - 'LOGNAME': 'cltbld', 19:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:43 INFO - 'MOZ_NO_REMOTE': '1', 19:07:43 INFO - 'NO_EM_RESTART': '1', 19:07:43 INFO - 'PAGER': '/bin/cat', 19:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:43 INFO - 'PWD': '/builds/slave/test', 19:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:43 INFO - 'SHELL': '/bin/bash', 19:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:43 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:43 INFO - 'USER': 'cltbld', 19:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:43 INFO - 'XPC_FLAGS': '0x0', 19:07:43 INFO - 'XPC_SERVICE_NAME': '0', 19:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:43 INFO - Downloading/unpacking jsonschema==2.5.1 19:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:07:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:46 INFO - Downloading functools32-3.2.3-2.tar.gz 19:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:07:46 INFO - warning: no files found matching '*.txt' 19:07:46 INFO - no previously-included directories found matching 'build' 19:07:46 INFO - no previously-included directories found matching 'dist' 19:07:46 INFO - no previously-included directories found matching '.git*' 19:07:46 INFO - Installing collected packages: jsonschema, functools32 19:07:46 INFO - Running setup.py install for functools32 19:07:47 INFO - warning: no files found matching '*.txt' 19:07:47 INFO - no previously-included directories found matching 'build' 19:07:47 INFO - no previously-included directories found matching 'dist' 19:07:47 INFO - no previously-included directories found matching '.git*' 19:07:47 INFO - Successfully installed jsonschema functools32 19:07:47 INFO - Cleaning up... 19:07:47 INFO - Return code: 0 19:07:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:07:47 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')]} 19:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:47 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')]} 19:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:47 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:47 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 19:07:47 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 19:07:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:47 INFO - 'HOME': '/Users/cltbld', 19:07:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:47 INFO - 'LOGNAME': 'cltbld', 19:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:47 INFO - 'MOZ_NO_REMOTE': '1', 19:07:47 INFO - 'NO_EM_RESTART': '1', 19:07:47 INFO - 'PAGER': '/bin/cat', 19:07:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:47 INFO - 'PWD': '/builds/slave/test', 19:07:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:47 INFO - 'SHELL': '/bin/bash', 19:07:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:47 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:47 INFO - 'USER': 'cltbld', 19:07:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:47 INFO - 'XPC_FLAGS': '0x0', 19:07:47 INFO - 'XPC_SERVICE_NAME': '0', 19:07:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:07:47 INFO - Cleaning up... 19:07:47 INFO - Return code: 0 19:07:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:07:47 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')]} 19:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:47 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')]} 19:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:47 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:47 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 19:07:47 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 19:07:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:47 INFO - 'HOME': '/Users/cltbld', 19:07:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:47 INFO - 'LOGNAME': 'cltbld', 19:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:47 INFO - 'MOZ_NO_REMOTE': '1', 19:07:47 INFO - 'NO_EM_RESTART': '1', 19:07:47 INFO - 'PAGER': '/bin/cat', 19:07:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:47 INFO - 'PWD': '/builds/slave/test', 19:07:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:47 INFO - 'SHELL': '/bin/bash', 19:07:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:47 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:47 INFO - 'USER': 'cltbld', 19:07:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:47 INFO - 'XPC_FLAGS': '0x0', 19:07:47 INFO - 'XPC_SERVICE_NAME': '0', 19:07:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:47 INFO - Downloading/unpacking blobuploader==1.2.4 19:07:47 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 19:07:47 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 19:07:47 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 19:07:47 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 19:07:47 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 19:07:47 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 19:07:50 INFO - Downloading blobuploader-1.2.4.tar.gz 19:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:07:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:07:50 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 19:07:50 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 19:07:50 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 19:07:50 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 19:07:50 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 19:07:50 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 19:07:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:07:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:51 INFO - Downloading docopt-0.6.1.tar.gz 19:07:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:07:51 INFO - Installing collected packages: blobuploader, requests, docopt 19:07:51 INFO - Running setup.py install for blobuploader 19:07:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:07:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:07:51 INFO - Running setup.py install for requests 19:07:52 INFO - Running setup.py install for docopt 19:07:52 INFO - Successfully installed blobuploader requests docopt 19:07:52 INFO - Cleaning up... 19:07:52 INFO - Return code: 0 19:07:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07: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')]} 19:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07: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')]} 19:07:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:52 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:52 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 19:07:52 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 19:07:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:52 INFO - 'HOME': '/Users/cltbld', 19:07:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:52 INFO - 'LOGNAME': 'cltbld', 19:07:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:52 INFO - 'MOZ_NO_REMOTE': '1', 19:07:52 INFO - 'NO_EM_RESTART': '1', 19:07:52 INFO - 'PAGER': '/bin/cat', 19:07:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:52 INFO - 'PWD': '/builds/slave/test', 19:07:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:52 INFO - 'SHELL': '/bin/bash', 19:07:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:52 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:52 INFO - 'USER': 'cltbld', 19:07:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:52 INFO - 'XPC_FLAGS': '0x0', 19:07:52 INFO - 'XPC_SERVICE_NAME': '0', 19:07:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-VY3ZQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:52 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-SRF_SO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-NC0ukQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-p_9EGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-kNPMWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-O2DqHw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-XU_xMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-A3lRkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-B8kOx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-XddvHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:53 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-nf3hH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-5KqJG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-XLeGKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-jgkILI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-0aVkjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-Ek9pBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:54 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-f70eOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:07:54 INFO - Running setup.py install for manifestparser 19:07:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:07:54 INFO - Running setup.py install for mozcrash 19:07:54 INFO - Running setup.py install for mozdebug 19:07:55 INFO - Running setup.py install for mozdevice 19:07:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Running setup.py install for mozfile 19:07:55 INFO - Running setup.py install for mozhttpd 19:07:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Running setup.py install for mozinfo 19:07:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Running setup.py install for mozInstall 19:07:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:07:55 INFO - Running setup.py install for mozleak 19:07:55 INFO - Running setup.py install for mozlog 19:07:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:07:56 INFO - Running setup.py install for moznetwork 19:07:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:07:56 INFO - Running setup.py install for mozprocess 19:07:56 INFO - Running setup.py install for mozprofile 19:07:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:07:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:07:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:07:56 INFO - Running setup.py install for mozrunner 19:07:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:07:56 INFO - Running setup.py install for mozscreenshot 19:07:56 INFO - Running setup.py install for moztest 19:07:57 INFO - Running setup.py install for mozversion 19:07:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:07:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:07:57 INFO - Cleaning up... 19:07:57 INFO - Return code: 0 19:07:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:07:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:57 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:07:57 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 19:07:57 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 19:07:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:07:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:57 INFO - 'HOME': '/Users/cltbld', 19:07:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:57 INFO - 'LOGNAME': 'cltbld', 19:07:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:57 INFO - 'MOZ_NO_REMOTE': '1', 19:07:57 INFO - 'NO_EM_RESTART': '1', 19:07:57 INFO - 'PAGER': '/bin/cat', 19:07:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:57 INFO - 'PWD': '/builds/slave/test', 19:07:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:57 INFO - 'SHELL': '/bin/bash', 19:07:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:07:57 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:07:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:57 INFO - 'USER': 'cltbld', 19:07:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:57 INFO - 'XPC_FLAGS': '0x0', 19:07:57 INFO - 'XPC_SERVICE_NAME': '0', 19:07:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-8WdgA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:57 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)) 19:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-TWjedR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:57 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)) 19:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-uzwXzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:57 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)) 19:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-SwmP0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 19:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:57 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-WDuRtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:57 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)) 19:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-i5DbJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-0J0T6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-CvxUFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-ZzeVUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-tYsUEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-jI11Me-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-2m0tVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-6bwBLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-s_7q5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:58 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)) 19:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:58 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-DpREqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:59 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)) 19:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-N4AqE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 19:07:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:59 INFO - Running setup.py (path:/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/pip-2zoETy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:59 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)) 19:07:59 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)) 19:07:59 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)) 19:07:59 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)) 19:07:59 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:07:59 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:07:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:02 INFO - Downloading blessings-1.6.tar.gz 19:08:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:08:02 INFO - Installing collected packages: blessings 19:08:02 INFO - Running setup.py install for blessings 19:08:02 INFO - Successfully installed blessings 19:08:02 INFO - Cleaning up... 19:08:02 INFO - Return code: 0 19:08:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:08:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:02 INFO - Reading from file tmpfile_stdout 19:08:02 INFO - Current package versions: 19:08:02 INFO - blessings == 1.6 19:08:02 INFO - blobuploader == 1.2.4 19:08:02 INFO - docopt == 0.6.1 19:08:02 INFO - functools32 == 3.2.3-2 19:08:02 INFO - jsonschema == 2.5.1 19:08:02 INFO - manifestparser == 1.1 19:08:02 INFO - mozInstall == 1.12 19:08:02 INFO - mozcrash == 1.0 19:08:02 INFO - mozdebug == 0.1 19:08:02 INFO - mozdevice == 0.48 19:08:02 INFO - mozfile == 1.2 19:08:02 INFO - mozhttpd == 0.7 19:08:02 INFO - mozinfo == 0.9 19:08:02 INFO - mozleak == 0.1 19:08:02 INFO - mozlog == 3.4 19:08:02 INFO - moznetwork == 0.27 19:08:02 INFO - mozprocess == 0.23 19:08:02 INFO - mozprofile == 0.28 19:08:02 INFO - mozrunner == 6.13 19:08:02 INFO - mozscreenshot == 0.1 19:08:02 INFO - mozsystemmonitor == 0.3 19:08:02 INFO - moztest == 0.7 19:08:02 INFO - mozversion == 1.4 19:08:02 INFO - psutil == 3.1.1 19:08:02 INFO - requests == 1.2.3 19:08:02 INFO - wsgiref == 0.1.2 19:08:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:08:02 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')]} 19:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:03 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 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:08:03 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 19:08:03 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 19:08:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:08:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:03 INFO - 'HOME': '/Users/cltbld', 19:08:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:03 INFO - 'LOGNAME': 'cltbld', 19:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:03 INFO - 'MOZ_NO_REMOTE': '1', 19:08:03 INFO - 'NO_EM_RESTART': '1', 19:08:03 INFO - 'PAGER': '/bin/cat', 19:08:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:03 INFO - 'PWD': '/builds/slave/test', 19:08:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:03 INFO - 'SHELL': '/bin/bash', 19:08:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:08:03 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:08:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:03 INFO - 'USER': 'cltbld', 19:08:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:03 INFO - 'XPC_FLAGS': '0x0', 19:08:03 INFO - 'XPC_SERVICE_NAME': '0', 19:08:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 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)) 19:08:03 INFO - Cleaning up... 19:08:03 INFO - Return code: 0 19:08:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c993490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c909570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c23dcbac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, '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.gucLwZaERP/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.EpYcDMNlmG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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 19:08:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:08:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:08:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:03 INFO - 'HOME': '/Users/cltbld', 19:08:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:03 INFO - 'LOGNAME': 'cltbld', 19:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:03 INFO - 'MOZ_NO_REMOTE': '1', 19:08:03 INFO - 'NO_EM_RESTART': '1', 19:08:03 INFO - 'PAGER': '/bin/cat', 19:08:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:03 INFO - 'PWD': '/builds/slave/test', 19:08:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:03 INFO - 'SHELL': '/bin/bash', 19:08:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:08:03 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:08:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:03 INFO - 'USER': 'cltbld', 19:08:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:03 INFO - 'XPC_FLAGS': '0x0', 19:08:03 INFO - 'XPC_SERVICE_NAME': '0', 19:08:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:08:03 INFO - Cleaning up... 19:08:03 INFO - Return code: 0 19:08:03 INFO - Running post-action listener: _resource_record_post_action 19:08:03 INFO - Running post-action listener: _start_resource_monitoring 19:08:03 INFO - Starting resource monitoring. 19:08:03 INFO - [mozharness: 2016-12-30 03:08:03.858508Z] Finished create-virtualenv step (success) 19:08:03 INFO - [mozharness: 2016-12-30 03:08:03.859508Z] Running install step. 19:08:03 INFO - Running pre-action listener: _resource_record_pre_action 19:08:03 INFO - Running main action method: install 19:08:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:08:04 INFO - Reading from file tmpfile_stdout 19:08:04 INFO - Detecting whether we're running mozinstall >=1.0... 19:08:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:08:04 INFO - Reading from file tmpfile_stdout 19:08:04 INFO - Output received: 19:08:04 INFO - Usage: mozinstall [options] installer 19:08:04 INFO - Options: 19:08:04 INFO - -h, --help show this help message and exit 19:08:04 INFO - -d DEST, --destination=DEST 19:08:04 INFO - Directory to install application into. [default: 19:08:04 INFO - "/builds/slave/test"] 19:08:04 INFO - --app=APP Application being installed. [default: firefox] 19:08:04 INFO - mkdir: /builds/slave/test/build/application 19:08:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:08:35 INFO - Reading from file tmpfile_stdout 19:08:35 INFO - Output received: 19:08:35 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:08:35 INFO - Running post-action listener: _resource_record_post_action 19:08:35 INFO - [mozharness: 2016-12-30 03:08:35.962871Z] Finished install step (success) 19:08:35 INFO - [mozharness: 2016-12-30 03:08:35.963035Z] Running run-tests step. 19:08:35 INFO - Running pre-action listener: _resource_record_pre_action 19:08:35 INFO - Running main action method: run_tests 19:08:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:08:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:08:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:08:35 INFO - 2016-12-29 19:08:35.977 screenresolution[1629:7347] starting screenresolution argv=screenresolution get 19:08:35 INFO - 2016-12-29 19:08:35.980 screenresolution[1629:7347] Display 0: 1600x1200x32@60 19:08:35 INFO - 2016-12-29 19:08:35.985 screenresolution[1630:7350] starting screenresolution argv=screenresolution list 19:08:35 INFO - Available Modes on Display 0 19:08:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:08:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:08:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:08:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:08:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:08:36 INFO - Intel Iris: 19:08:36 INFO - Chipset Model: Intel Iris 19:08:36 INFO - Type: GPU 19:08:36 INFO - Bus: Built-In 19:08:36 INFO - VRAM (Dynamic, Max): 1536 MB 19:08:36 INFO - Vendor: Intel (0x8086) 19:08:36 INFO - Device ID: 0x0a2e 19:08:36 INFO - Revision ID: 0x0009 19:08:36 INFO - Displays: 19:08:36 INFO - Display: 19:08:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:08:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:08:36 INFO - Display Serial Number: 1600x1200 60 19:08:36 INFO - Main Display: Yes 19:08:36 INFO - Mirror: Off 19:08:36 INFO - Online: Yes 19:08:36 INFO - Rotation: Supported 19:08:36 INFO - Return code: 0 19:08:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:08:36 INFO - Python 2.7.3 19:08:36 INFO - Return code: 0 19:08:36 INFO - grabbing minidump binary from tooltool 19:08: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')]} 19:08:36 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 0x10c7a7d30>, '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 0x10c60ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c79fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:08:36 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 19:08:36 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 19:08:36 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 19:08:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:08:36 INFO - Return code: 0 19:08:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:08:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:08:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:08:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:08:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:08:36 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/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 19:08:36 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/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0188 --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 19:08:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gucLwZaERP/Render', 19:08:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:36 INFO - 'HOME': '/Users/cltbld', 19:08:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:36 INFO - 'LOGNAME': 'cltbld', 19:08:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:08:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:36 INFO - 'MOZ_NO_REMOTE': '1', 19:08:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:08:36 INFO - 'NO_EM_RESTART': '1', 19:08:36 INFO - 'PAGER': '/bin/cat', 19:08:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:36 INFO - 'PWD': '/builds/slave/test', 19:08:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:08:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:36 INFO - 'SHELL': '/bin/bash', 19:08:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EpYcDMNlmG/Listeners', 19:08:36 INFO - 'TMPDIR': '/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/', 19:08:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:36 INFO - 'USER': 'cltbld', 19:08:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:36 INFO - 'XPC_FLAGS': '0x0', 19:08:36 INFO - 'XPC_SERVICE_NAME': '0', 19:08:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:36 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/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0188', '--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 19:08:36 INFO - mozversion application_buildid: 20161229175220 19:08:36 INFO - mozversion application_changeset: 31516cfb0f4153cfddf65a9cf8fbdb8f88694922 19:08:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:08:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:08:36 INFO - mozversion application_name: Firefox 19:08:36 INFO - mozversion application_remotingname: firefox-dev 19:08:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:08:36 INFO - mozversion application_vendor: Mozilla 19:08:36 INFO - mozversion application_version: 52.0a2 19:08:36 INFO - mozversion platform_buildid: 20161229175220 19:08:36 INFO - mozversion platform_changeset: 31516cfb0f4153cfddf65a9cf8fbdb8f88694922 19:08:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:08:36 INFO - mozversion platform_version: 52.0a2 19:08:36 INFO - using testdate: 1483067316 19:08:36 INFO - actual date: 1483067316 19:08:36 INFO - starting webserver on 'localhost:49251' 19:08:36 INFO - SUITE-START | Running 2 tests 19:08:36 INFO - TEST-START | dromaeo_css 19:08:36 INFO - operating with platform_type : mac_ 19:08:36 INFO - Initialising browser for dromaeo_css test... 19:08:36 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmp6VWmJU/profile http://localhost:49251/getInfo.html) 19:08:39 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 19:08:39 INFO - PROCESS | 1640 | colorDepth:24 19:08:39 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 19:08:39 INFO - PROCESS | 1640 | __metrics 19:08:39 INFO - PROCESS | 1640 | 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" 19:08:39 INFO - PROCESS | 1640 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:08:40 INFO - TEST-INFO | 1640: exit 0 19:08:40 INFO - Browser initialized. 19:08:40 INFO - Running cycle 1/1 for dromaeo_css test... 19:08:40 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmp6VWmJU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:52 INFO - PROCESS | 1642 | RSS: Main: 222887936 19:08:52 INFO - PROCESS | 1642 | 19:09:43 INFO - PROCESS | 1642 | 1483067383960 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:11:57 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49251/tests/dromaeo/cssquery-ext.html) 19:11:58 INFO - PROCESS | 1642 | RSS: Main: 338284544 19:11:58 INFO - PROCESS | 1642 | 19:15:01 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-ext.html (next: http://localhost:49251/tests/dromaeo/cssquery-jquery.html) 19:15:02 INFO - PROCESS | 1642 | RSS: Main: 348467200 19:15:02 INFO - PROCESS | 1642 | 19:18:04 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49251/tests/dromaeo/cssquery-mootools.html) 19:18:05 INFO - PROCESS | 1642 | RSS: Main: 326029312 19:18:05 INFO - PROCESS | 1642 | 19:21:07 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49251/tests/dromaeo/cssquery-prototype.html) 19:21:08 INFO - PROCESS | 1642 | RSS: Main: 317607936 19:21:08 INFO - PROCESS | 1642 | 19:24:11 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49251/tests/dromaeo/cssquery-yui.html) 19:24:12 INFO - PROCESS | 1642 | RSS: Main: 308101120 19:24:12 INFO - PROCESS | 1642 | 19:27:14 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49251/tests/dromaeo/cssquery-yui.html (next: http://localhost:49251/tests/dromaeo/cssquery-dojo.html) 19:27:15 INFO - PROCESS | 1642 | RSS: Main: 459608064 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | __start_tp_report 19:27:15 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 19:27:15 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 19:27:15 INFO - PROCESS | 1642 | |i|pagename|runs| 19:27:15 INFO - PROCESS | 1642 | |0;dojo.html;3274.607047154341;3340.098173493157;3341.0311009807156;3356.63077061523;3374.8143852088124;5660.29246344207;5671;5745.310562732478;5754.108113242449;5791.739371728272;4814.759262036893;5629.690367029812;5653.6607803531815;5727.828165155052;5728.16941543476;5207.869803254911;5209.270702101709;5230.058589453897;5283.920741188886;5311.309529761125;5164;5187.221916712486;5217.321748172732;5239.764210610531;5326.360836699582;5196.844094677147;5298.682079075248;5304.389995150549;5312.57499400049;5337.893242135175;5274.2879711238875;5285.709285989269;5294.549963253128;5299.178627312773;5335.466453354672;4364.148990946766;5108.310378098983;5134.691918484903;5175.275461435402;5218.165093585008;5625.8593535161535;5683.744231509592;5699.173619825144;5721.998650236192;5722.399148089474;6130.929516759049;6179.721912513903;6192.752289908398;6195.0707393890825;6200.968995155041;6158.8768224635705;6220.253569571681;6220.377962203788;6226.6264024158245;6231.470325022333;5734.713264336767;5762.049261871786;5762.30852297727;5828.300603927556;5867.970660146715;6339.683015849189;6346.619202847844;6350.0792385103905;6354.3963323484195;6356.968215158987;5974.2830860297045;6028.125921741323;6053.303870055;6191.19514463117;6206.968965155235;6174.969125154345;6178.598391104564;6248.094026366151;6299.338569450142;6300.243970723543;6243.562950593415;6248.9062664060075;6264.404881536247;6269.435750782452;6277.529185311028;6120.296165940885;6146.862830376394;6171.59884607499;6248.75004999804;6272.717727702265;4896.387951506062;4922.409310882717;4947.356843610308;4947.876303092451;4950.381202349707;4417.315316126005;4427.424434823452;4445.022095139064;4460.8661740147845;4466.285394336891;5709.257796486513;5798.594098413155;5800.9129863052085;5812.21535092757;5835.095560188178;6131.101423540749;6173.69131543421;6176.567640265138;6194.566380353423;6258.249010118815;5769.221155144086;5842.649441033552;5872.236609240771;5887.2052272943465;5891.734871930774;5414.052540805344;5420.078586640202;5426.728663566806;5426.9185962210595;5427.402985671573;6128.564234752123;6273.52948528853;6392.26488953767;6406.967965160145;6411.551191416556;6155.446009859134;6182.783602573888;6213.65824879636;6219.968900155471;6278.623282603058;5151.922721159261;6380.93619063822;6396.712147953446;6411.903821442776;6448.129502517288;6186.19579454668;6224.066390041349;6226;6248.312685604671;6248.718807653576;6110.862540392742;6145.231846019247;6163.691815409301;6224.751009959732;6248.4376406123665;6121.265448146251;6157.599756015845;6158.107074474266;6158.9221886170635;6164.229471316085;6124.295705993868;6143.416375444325;6151.876962460637;6153.907691384543;6162.537809664203;5652.689102099424;5715.599908006399;5871.236739223874;5890.823275301748;5910.379410161871;4648.883777905579;5786.103154011135;6124.686092398567;6138.419052470106;6162.322144564005;5755.482006619424;5820.068788993741;5976.7908123215475;5997.430244126976;5999.580029398075 19:27:15 INFO - PROCESS | 1642 | |1;ext.html;496.48613684836204;504.3745755263472;508.2224196978623;515.7369741431869;546.2461802712256;30885.691143088563;40780.59219407809;41912.95217619561;42018.15963680724;42126.789366053126;42739;42848.57151428485;42937.570624293745;43019.78490107553;43030;9393.812123757521;9564.808703825955;9576.329656924017;9579.089986451276;9596.32825702201;9409.247260219201;9529.951705312411;9534.332596718232;9536.713898583053;9544.522773861314;9489.335746497747;9556.522173891277;9560.282978776584;9564.091411315914;9570.186534144572;9475.147236748691;9550.188234000154;9554.140127388568;9567.473788941605;9568.234541236685;9367.765805854908;9398.38910470824;9438.90561094394;9442.291828112919;9455.858162127504;39541.01147471307;39883.40174897379;39930.001749956486;39962.80018599889;40019;3205.8717651294155;3246.4156451838658;3310.9834450827834;3688.2112175594816;3822.311983842907;28702.282442939093;28745;30347.696523034927;31033.844830775928;31238;3163.6678148794276;3183.522471629251;3198.74410047198;3250.577424934767;3275.410426123297;432216;432528;435907.82046089886;435968;452434;33246.66753332484;33849.492257616155;34233.657663423044;34913;59566.404335956955;30113.247168820955;35817;35993.64006359903;36028.81985590108;36108.45837312443;14341.426342946374;14464.20446875432;14493.637659058608;14501.129932204101;14526.346314415876;12231;12275.079369047357;12317;12360.629181124581;12362.196457230251;7823.530588164728;7832.765017049498;7848.372130229568;7892.763217103496;7935.08746494149;3206.374756922378;3208.262099717078;3213.694699003591;3224.774265801418;3263.184203949013;944.263474489899;990.3760630802549;1024.349538043333;1034.4103860799273;1059.184427990443;29581;29730.25674358115;30123.548146777815;30348.84825575902;30624.387512250076;902.9909700902907;916.0656130746601;953.4327075390132;987.6790043235977;1092.5083712329483;15032.398704051715;15534.67955223796;15552.611184720246;15897.841021589868;15908.920455397649;667238;749336;756554;760361.1981940055;953700;59099.81800364055;59626.70186649126;59743.70128149331;59768.70115649394;60112.69943650254;1161.1233518693332;1161.1523587781137;1198.2750435986102;1353.9323033848268;1378.6139880833382;22931;30724.385512290075;31393.372132557677;31496.89760858359;32263;56045.15932261101;56588;56601;56658.58353541195;56678.14982775179;580307.0984645049;583591;585326;589064;590166;26888.59667104956;27427.588586170823;35524.467132993545;36207.818960905024;36230.818845905604;36708.632913670524;37969.24061518809;39525.01187470336;47450;49328.50671493239;37002.814985924895;39454.40818387784;39734;40893;42021.579784201764;38740.225195496496;38795.806020969714;39096.80451597724;40383.76929845547;40404.393934090425;37603.81198094101;38269;38294.042648934;38404;40024.799256022365;31833.8858139964;32359.838200808845;34037.82981085079;35078.12304692403;35103.82448087743;20032.44200742394;25772.742272577034;25787.96848126129;26196.8690156548;26311.60532592051 19:27:15 INFO - PROCESS | 1642 | |2;jquery.html;316.86533223380064;369.09387453800923;369.6709928163934;392.74864086984354;405.44251653975783;19214.807851921494;19806.009699515038;19993.400197994084;20679;20772.792272077273;19999.000049997514;20264.493387665316;20595.38213853583;20717.896410517926;20875.478113047182;5807.30312362516;5912.497437717778;5965.552583556229;6012.5791194616395;6089.9342615042415;4173.4157217989505;5929.970350148244;6041.275046994367;6043.274807023164;6043.667598282114;4382.430284063067;4391.670624703144;4424.40270563473;4434.068845542424;6655.800325990203;6463.030545418178;6696.698648560802;6717.899231511531;6720.092787473702;6726.630035348054;5598.300212473441;5809.390014048548;5865.325487568942;5867.178594996704;5881.3530511643685;23686.526269474685;25561.744382556124;25691.743082569123;25801.741982580123;25985;2215.1582398688556;2216.4015715756655;2244.764299748519;2255.5037891663806;2312.2369618026178;5346.572274218053;5634.577406694473;7211.675474603603;10407.115395191418;10719.410432426292;1820.6085691576375;1856.9535761605932;1857.851371890259;1859.4514618187668;1908.6564418404682;89925.65111523333;127480;128456;129867;131595;9468.715938521855;11497.195196336175;11740.471678774305;11788.88211117895;11927.224730392496;15109.773353399707;15143.015703979207;15153.924230378778;15165.393384264726;15172.468963586442;8352.624131914079;8571.357148213909;8589.14108589136;8722.215000649972;8771.736847894446;8409.705660301861;8411.747647570583;8422.915770842214;8534.87197692035;8559.058503564604;4541.841035563805;4682.836100736458;4698.694584851974;4701.64737644678;4734.881627959259;1852.8054554271878;1913.9712904306446;1920.7310976463307;1921.1258877210835;1923.7210604462555;758.5751978891839;863.9395242333102;883.4169448164181;915.706973768401;979.7991411760572;20663.58672826535;21703.914804259723;21954.560908781736;22115.889420552794;22117.115315387204;876.7019213467452;942.5240253671852;943.3444852739516;950.1733491861986;959.8800149981253;8916.509591972504;10883.455827208609;11256.437178141061;11285.435728213557;11399.20205585601;593264;609068;614779;647872;647899;101722;125300;128475.07287390696;135861;136438;944.3955868244329;967.2793768642406;995.8954309797367;1019.8623185869961;1021.1830535571513;19310.131044102767;20040.797552146916;20439.795602044385;20449.897750511747;20467.181312747325;30510.542341864446;48783.512164877895;56022.71988640031;56222;56962.71518642546;80214;81383.5930820342;81533;82494.98792602697;82782.58608706918;7420.962895185706;8194.180581941997;8387.203215694499;8429.4942303462;8489.490630562186;11290.322580645188;13153.671158221121;13153.868461315264;13462.586428424875;13725.656858578615;10911.127109831035;11255.437228138397;11378.601748939078;11474.541018359172;11727.824082638597;10964.969008367078;11326.716832079264;11376.54493820238;11608.709782255512;11725;11378.54485820558;11599.304041757521;11680.240784348991;11832.46753896094;11863.347515886364;8195.60674685293;8355.206255405727;8420.957895210484;8646.005709343255;8686.47881127109;8831.514266715392;11273.379964102052;11284.21010529255;11387.544498219979;11817.645470635895 19:27:15 INFO - PROCESS | 1642 | |3;mootools.html;1269.5048930916944;1435.1604311477781;1554.4481708993308;1748.3531093495408;1802.8377446029847;8082.474639148459;8421.073681894994;8425.36809739269;8425.915740842589;8454.915450845481;7516.699332026712;7766.029246344207;7787.532748035108;8132.308753755938;8208.425410221287;2333.288347054148;2372.4543355028354;2372.8101751859895;2375.596148654733;2379.0721618568773;2352.5530149271585;2375.8930848111877;2391.3065211088765;2405.6391541268777;2407.374082738492;1987.6124155789555;2347.049444974788;2371.6679664846934;2377.785999260066;2379.773921477457;2175.5431359414542;2326.499802542453;2339.9181028663916;2343.7304709958403;2350.471143992588;2163.4266919266406;2378.5599664062206;2386.05750728463;2389.211560185152;2390.521895620883;8363.247307742333;8400.621972011213;8455.53494557799;8470.745877623682;8475.152484751537;4734.147853386389;5073.492650734934;5082.669626474304;5247.291615631917;5257.684538927677;2888.5667149927467;3281.737461003114;3623.5108260385036;3630.0561853917907;3634.8182590870615;1052.3273774526922;1052.7473406382412;1062.4793851012948;1089.4607169451094;1095.386583513235;10797.190210734227;10929.672109836718;10952.069074128629;10962.068224200873;10963.890361096446;4541.545845415432;4672.766361681903;4780.880477988008;4801.087793319258;4805.735684537384;9476.905230947741;9478.763030924145;9482.762930926781;9483.952580237055;9500.667476638284;5204.297419848272;5213.113770658997;5222;5223.451537588575;5234.738263086831;4063.6139566741113;4225.345071513921;4779.402574678165;4794.232922732346;4831.95168048322;3285.227971426713;3810.466534685146;3830.7893065881085;3886.397608370707;3927.8232479538487;1086.2124959404528;1251.7997120460682;1255.453877563263;1260.1053252190864;1271.7774389481804;757.7575175943755;860.337540094124;900.243795212025;900.9954950225207;903.408267584733;4797.904041919142;5624.846906384181;5678.091505359205;5979.402059793986;6006.489448396847;970.5875003123786;998.8601595776597;1011.7217765114506;1024.293237666008;1044.2951008069522;3775.679067279312;3835.9232815343535;3845.288621605012;3932.9577661919616;3968.4840970674204;9870.802583948282;10323.793524129474;10330.48347582633;10342.948285258526;10346.844797328045;10251.794964100676;10277.640282590073;10286.537105830255;10292.073713365833;10299.794004119876;7577.772666820004;7815.0231221097365;7848.960755196187;7937.920620793718;7992.3606111510935;5510.504054635102;5572.3034620672415;5650.519705825051;5685.204071430086;5727.198192253005;8257.339412847077;9350.298727595322;9534;9590.232781377612;9592.616295348387;9549.522523873917;9582.425054496753;9654.372465789702;9659.613615455584;9697.981711920147;5759;5999.49004334619;6466.51501137426;6753.425958793558;6902.654867256718;7756.185600511865;7936.2857342839425;7986.840263194587;8039.758807235792;8045.195480451908;7136.500444968802;7227;7253.347198752138;7262.636868156676;7381.483296169216;7035.155781306276;7095.25950347162;7124.501284910007;7220;7280.017197678246;6665.366790154928;6667.566608170648;6728.259891412039;6744.123263975652;6756.256811750605;4885.95114048855;4897.9020419592125;4910.729909854992;4912.45962944083;4948.579370753526;4111.732737372061;4667.8599642010795;4746.38297021385;4799.520047995173;4919.606431485401 19:27:15 INFO - PROCESS | 1642 | |4;prototype.html;298.17851818240746;301.83248297195047;306.55549453292724;313.1919647309941;314.82841851191097;4962.848591144622;5125.69245845249;5154.938024790086;5171.224316352549;5177.922331165036;4945.3323801286815;5005.049040682268;5072.148665737007;5081.28861959326;5108.386993560778;3677.650623190806;3951.466552015484;3956.4460975463458;3960.8613698520558;3963.504561929766;3805.600411956746;3969.702272329572;3971.900702482447;3973.4239184733683;4007.499062617173;3904.609539046101;3908.1011367385518;3922.293987082324;3932.4297976793346;3961.564227934925;3921.2353591049614;3922.4508568800393;3925.156091440354;3927.8821635350987;3934.488516492838;2930.0477344862793;2982.4929761940307;3360.2271477560053;3371;3458.4466485362473;4347;4935.235038569028;4964.180910149821;4980.352554167971;4999.8250061248045;3690.852365905361;3994.6018893387372;4130.6282434581035;4399.824007039714;4403.244495944405;4854.029194161147;4897.363342765418;4953.504649535053;5281.8151364702435;5342.652727572696;3115.4547954108166;3285.720713739335;3691.2248427830186;3703.092742278132;3722.9069273268383;5557.888842223173;5584.720763961785;5589.916151257735;5590.161475778625;5593.496585307342;3650.361186792301;3753.418220175877;3767.811609419518;3801.4487899254455;3802.5817160112365;2961.2448825549777;3518.5777706675362;3801.087738942689;3840.558335791364;3857.363535016747;3947.1513624570857;4416.315471101984;4428.800703968264;4449.310356894686;4457.955420445754;4548.840790572314;5117.846464605993;5122.92315615266;5445.918311225256;5446.972765136149;1022.4744880146027;1049.8267785815256;1141.6917432293235;1171;1229.846269216348;760.9384908053235;795.3716563914559;823.8671826238922;843.5571325054395;846.8221673448521;4072.780334717527;4221.395869569544;4712.340272361872;4716.457607375127;4740.360051393087;4940.629452791055;5012.872103776694;5049.974750126225;5065.696058236444;5101.566366858784;3981.6018398159954;4644.674872758942;4678.994016286515;4691.366665500182;4739.431268247763;2214.867107973527;2337.8842147856217;2667.6798784146026;2682.9329266768486;2688;4577.038821847449;4792.825757689388;4820.734859582757;4969.627277954096;5267.945501254514;4737.620990320766;4806.975965120152;4839.346688197048;5193.3767947845745;5248.921266181011;4120.608542188487;4480.647659978537;4726.385569875894;5204.308599705036;5227.536289838851;4136.648384887319;4213;4363.3018717005125;5069.898602028013;5114.897702045864;4315.374270730664;4989.2516122580455;5055.090083784954;5060.661002642248;5073.670211436245;4226.344916537942;4358.564143585743;4730.6215502759005;4864.781084851119;5275.604329675367;3264.79626252274;3496.461556914949;3528.2943411317324;3530.223350862806;3589.869191204762;4656.627469802475;4666.923219167438;5027.3551675035305;5075.462268865567;5078.669886457368;4213.747175169499;4469.284914413678;4823.204171311834;5099.603186502338;5385.515303622693;4453.617314736601;4713.457952335525;4851.975740121276;5307.310049693515;5325.707086110301;4963.950360496493;5240.318758561362;5242.213667949838;5253.185756207794;5263.578913686819;4465.995151091109;4719.976400118115;4747.548982846624;4817.084753896748;4980.800767969386;4442.3558584004495;4966.652334336706;5013.498650135103;5160.193932123727;5362.865928351823 19:27:15 INFO - PROCESS | 1642 | |5;yui.html;672.320955834607;682.8702546516162;686.6738299307826;742.2206682982867;765.1162906842595;13949.163050216994;14018.878489720822;14583.781243281333;14645.633859153526;14825;12994.74010519789;13134.277614731185;13136.408861601227;13167.407466663999;13184.670383240424;628.6950828848016;647.9157709497758;680.8468094678707;785.4305628419407;814.8899898513714;815.5351449673689;817.2236375443323;819.0130892274794;820.3232333325001;821.9383546234027;863.0678866823847;863.8315528471951;864;864.5072308783964;865.7662431143588;856.5417501636657;857.7469646454311;859.6217664227751;860.5138096975189;860.9268212201939;633.3571425003626;633.5786701843268;633.822529691687;635.6345101566618;637.8309747916805;13491.797623035662;13763.31183440824;13767.586972390744;13806.447742090304;14116.85883141176;13293.401796919163;13321.333933303393;13540.458381664721;13706.21323201156;13871.791923121162;11013.2841365311;11077.667669969915;11285.322880627189;11406.201565890391;11536.077113830874;3552.8045957472586;3567.161716996504;3571.4285714285847;3659.9085022874374;3731.3470142724914;85663;87936.12063879344;87998.5600072002;89540;89789.10210897872;10254.692359229235;10331.896681033093;10365.896341036643;10390.168786497063;10419.218558607983;20376.796232037785;20414.285500007725;20466.079026443847;20471;20480.283190088274;7237.565746055254;7464.813379665552;7553.1313898901235;7742.109657389472;7801;4071.206114807643;4132.834686612502;4158.313878210061;4291.785410729451;4358.259095953696;3330.900072997814;3401.2517246205794;3406.318736252759;3474.0793689672255;3479.1650003998857;636.9108324834527;640.2636967487334;644.7485480662546;655.1450357283695;655.6426747422666;529.7960285290114;625.4683519008806;637.3371693438864;650.8730797494538;660.92729799722;8071.313938315191;8826.42628229163;8839.558022098869;9081.137291957253;9124.954375228213;602.9125776762345;641.2497378067673;660.2901880478505;663.8639078988796;669.0833558025536;4022.416749571354;4216.7259128156575;4219.113986062961;4461.263891457938;4548.158590660738;91154;91339;94500.52749736345;100834;118776;73684.21052631669;81824.59087704524;86916;88929;89555.55222223848;78985.60507197428;81355;82446.17553824386;85879.71180432424;88494;9176.174144327042;9383.953080234554;9533.809323813346;9546;9568.856467152858;46182.61452156441;50486;50516.74741626268;53263;53935.73032134814;68281.65859170673;68946.65526672335;70587;74764;76593;12253.32606706604;12409;12424.446944131983;12439;12562.309073001074;12610.58308122447;13535.351527559384;14335.56993290203;14475.638109047359;14673.62559501821;13081.215127092404;13103.934480327538;13195.305458873108;13281.933590331986;13506.054576179966;10965.067969222706;11711.063114950613;13016.739665206453;13328.466861325438;13392.665183370103;12255.203411778206;12293.078019148421;12653.620391388273;12735.490580376681;13276.867231327564;9764.707058788248;9932.106110450095;11426.657200284004;11476.540938362372;11487.885121148682;9321.953390233006;11259.09927205838;11342.773144537228;11345.205835591429;11415.828762568734 19:27:15 INFO - PROCESS | 1642 | __end_tp_report 19:27:15 INFO - PROCESS | 1642 | __start_cc_report 19:27:15 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1364 19:27:15 INFO - PROCESS | 1642 | __end_cc_report 19:27:15 INFO - PROCESS | 1642 | __startTimestamp1483068435199__endTimestamp 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | ------- Summary: start ------- 19:27:15 INFO - PROCESS | 1642 | Number of tests: 6 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5732.06 Median:5890.82 stddev:653.01 (11.1%) stddev-sans-first:625.91 19:27:15 INFO - PROCESS | 1642 | Values: 3274.6 3340.1 3341.0 3356.6 3374.8 5660.3 5671.0 5745.3 5754.1 5791.7 4814.8 5629.7 5653.7 5727.8 5728.2 5207.9 5209.3 5230.1 5283.9 5311.3 5164.0 5187.2 5217.3 5239.8 5326.4 5196.8 5298.7 5304.4 5312.6 5337.9 5274.3 5285.7 5294.5 5299.2 5335.5 4364.1 5108.3 5134.7 5175.3 5218.2 5625.9 5683.7 5699.2 5722.0 5722.4 6130.9 6179.7 6192.8 6195.1 6201.0 6158.9 6220.3 6220.4 6226.6 6231.5 5734.7 5762.0 5762.3 5828.3 5868.0 6339.7 6346.6 6350.1 6354.4 6357.0 5974.3 6028.1 6053.3 6191.2 6207.0 6175.0 6178.6 6248.1 6299.3 6300.2 6243.6 6248.9 6264.4 6269.4 6277.5 6120.3 6146.9 6171.6 6248.8 6272.7 4896.4 4922.4 4947.4 4947.9 4950.4 4417.3 4427.4 4445.0 4460.9 4466.3 5709.3 5798.6 5800.9 5812.2 5835.1 6131.1 6173.7 6176.6 6194.6 6258.2 5769.2 5842.6 5872.2 5887.2 5891.7 5414.1 5420.1 5426.7 5426.9 5427.4 6128.6 6273.5 6392.3 6407.0 6411.6 6155.4 6182.8 6213.7 6220.0 6278.6 5151.9 6380.9 6396.7 6411.9 6448.1 6186.2 6224.1 6226.0 6248.3 6248.7 6110.9 6145.2 6163.7 6224.8 6248.4 6121.3 6157.6 6158.1 6158.9 6164.2 6124.3 6143.4 6151.9 6153.9 6162.5 5652.7 5715.6 5871.2 5890.8 5910.4 4648.9 5786.1 6124.7 6138.4 6162.3 5755.5 5820.1 5976.8 5997.4 5999.6 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:71269.19 Median:39533.01 stddev:166548.54 (421.3%) stddev-sans-first:166930.98 19:27:15 INFO - PROCESS | 1642 | Values: 496.5 504.4 508.2 515.7 546.2 30885.7 40780.6 41913.0 42018.2 42126.8 42739.0 42848.6 42937.6 43019.8 43030.0 9393.8 9564.8 9576.3 9579.1 9596.3 9409.2 9530.0 9534.3 9536.7 9544.5 9489.3 9556.5 9560.3 9564.1 9570.2 9475.1 9550.2 9554.1 9567.5 9568.2 9367.8 9398.4 9438.9 9442.3 9455.9 39541.0 39883.4 39930.0 39962.8 40019.0 3205.9 3246.4 3311.0 3688.2 3822.3 28702.3 28745.0 30347.7 31033.8 31238.0 3163.7 3183.5 3198.7 3250.6 3275.4 432216.0 432528.0 435907.8 435968.0 452434.0 33246.7 33849.5 34233.7 34913.0 59566.4 30113.2 35817.0 35993.6 36028.8 36108.5 14341.4 14464.2 14493.6 14501.1 14526.3 12231.0 12275.1 12317.0 12360.6 12362.2 7823.5 7832.8 7848.4 7892.8 7935.1 3206.4 3208.3 3213.7 3224.8 3263.2 944.3 990.4 1024.3 1034.4 1059.2 29581.0 29730.3 30123.5 30348.8 30624.4 903.0 916.1 953.4 987.7 1092.5 15032.4 15534.7 15552.6 15897.8 15908.9 667238.0 749336.0 756554.0 760361.2 953700.0 59099.8 59626.7 59743.7 59768.7 60112.7 1161.1 1161.2 1198.3 1353.9 1378.6 22931.0 30724.4 31393.4 31496.9 32263.0 56045.2 56588.0 56601.0 56658.6 56678.1 580307.1 583591.0 585326.0 589064.0 590166.0 26888.6 27427.6 35524.5 36207.8 36230.8 36708.6 37969.2 39525.0 47450.0 49328.5 37002.8 39454.4 39734.0 40893.0 42021.6 38740.2 38795.8 39096.8 40383.8 40404.4 37603.8 38269.0 38294.0 38404.0 40024.8 31833.9 32359.8 34037.8 35078.1 35103.8 20032.4 25772.7 25788.0 26196.9 26311.6 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:35794.84 Median:15413.70 stddev:103862.20 (673.8%) stddev-sans-first:104119.40 19:27:15 INFO - PROCESS | 1642 | Values: 316.9 369.1 369.7 392.7 405.4 19214.8 19806.0 19993.4 20679.0 20772.8 19999.0 20264.5 20595.4 20717.9 20875.5 5807.3 5912.5 5965.6 6012.6 6089.9 4173.4 5930.0 6041.3 6043.3 6043.7 4382.4 4391.7 4424.4 4434.1 6655.8 6463.0 6696.7 6717.9 6720.1 6726.6 5598.3 5809.4 5865.3 5867.2 5881.4 23686.5 25561.7 25691.7 25801.7 25985.0 2215.2 2216.4 2244.8 2255.5 2312.2 5346.6 5634.6 7211.7 10407.1 10719.4 1820.6 1857.0 1857.9 1859.5 1908.7 89925.7 127480.0 128456.0 129867.0 131595.0 9468.7 11497.2 11740.5 11788.9 11927.2 15109.8 15143.0 15153.9 15165.4 15172.5 8352.6 8571.4 8589.1 8722.2 8771.7 8409.7 8411.7 8422.9 8534.9 8559.1 4541.8 4682.8 4698.7 4701.6 4734.9 1852.8 1914.0 1920.7 1921.1 1923.7 758.6 863.9 883.4 915.7 979.8 20663.6 21703.9 21954.6 22115.9 22117.1 876.7 942.5 943.3 950.2 959.9 8916.5 10883.5 11256.4 11285.4 11399.2 593264.0 609068.0 614779.0 647872.0 647899.0 101722.0 125300.0 128475.1 135861.0 136438.0 944.4 967.3 995.9 1019.9 1021.2 19310.1 20040.8 20439.8 20449.9 20467.2 30510.5 48783.5 56022.7 56222.0 56962.7 80214.0 81383.6 81533.0 82495.0 82782.6 7421.0 8194.2 8387.2 8429.5 8489.5 11290.3 13153.7 13153.9 13462.6 13725.7 10911.1 11255.4 11378.6 11474.5 11727.8 10965.0 11326.7 11376.5 11608.7 11725.0 11378.5 11599.3 11680.2 11832.5 11863.3 8195.6 8355.2 8421.0 8646.0 8686.5 8831.5 11273.4 11284.2 11387.5 11817.6 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5461.19 Median:4911.59 stddev:3003.88 (61.2%) stddev-sans-first:2995.79 19:27:15 INFO - PROCESS | 1642 | Values: 1269.5 1435.2 1554.4 1748.4 1802.8 8082.5 8421.1 8425.4 8425.9 8454.9 7516.7 7766.0 7787.5 8132.3 8208.4 2333.3 2372.5 2372.8 2375.6 2379.1 2352.6 2375.9 2391.3 2405.6 2407.4 1987.6 2347.0 2371.7 2377.8 2379.8 2175.5 2326.5 2339.9 2343.7 2350.5 2163.4 2378.6 2386.1 2389.2 2390.5 8363.2 8400.6 8455.5 8470.7 8475.2 4734.1 5073.5 5082.7 5247.3 5257.7 2888.6 3281.7 3623.5 3630.1 3634.8 1052.3 1052.7 1062.5 1089.5 1095.4 10797.2 10929.7 10952.1 10962.1 10963.9 4541.5 4672.8 4780.9 4801.1 4805.7 9476.9 9478.8 9482.8 9484.0 9500.7 5204.3 5213.1 5222.0 5223.5 5234.7 4063.6 4225.3 4779.4 4794.2 4832.0 3285.2 3810.5 3830.8 3886.4 3927.8 1086.2 1251.8 1255.5 1260.1 1271.8 757.8 860.3 900.2 901.0 903.4 4797.9 5624.8 5678.1 5979.4 6006.5 970.6 998.9 1011.7 1024.3 1044.3 3775.7 3835.9 3845.3 3933.0 3968.5 9870.8 10323.8 10330.5 10342.9 10346.8 10251.8 10277.6 10286.5 10292.1 10299.8 7577.8 7815.0 7849.0 7937.9 7992.4 5510.5 5572.3 5650.5 5685.2 5727.2 8257.3 9350.3 9534.0 9590.2 9592.6 9549.5 9582.4 9654.4 9659.6 9698.0 5759.0 5999.5 6466.5 6753.4 6902.7 7756.2 7936.3 7986.8 8039.8 8045.2 7136.5 7227.0 7253.3 7262.6 7381.5 7035.2 7095.3 7124.5 7220.0 7280.0 6665.4 6667.6 6728.3 6744.1 6756.3 4886.0 4897.9 4910.7 4912.5 4948.6 4111.7 4667.9 4746.4 4799.5 4919.6 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4157.74 Median:4661.78 stddev:1246.21 (26.7%) stddev-sans-first:1215.57 19:27:15 INFO - PROCESS | 1642 | Values: 298.2 301.8 306.6 313.2 314.8 4962.8 5125.7 5154.9 5171.2 5177.9 4945.3 5005.0 5072.1 5081.3 5108.4 3677.7 3951.5 3956.4 3960.9 3963.5 3805.6 3969.7 3971.9 3973.4 4007.5 3904.6 3908.1 3922.3 3932.4 3961.6 3921.2 3922.5 3925.2 3927.9 3934.5 2930.0 2982.5 3360.2 3371.0 3458.4 4347.0 4935.2 4964.2 4980.4 4999.8 3690.9 3994.6 4130.6 4399.8 4403.2 4854.0 4897.4 4953.5 5281.8 5342.7 3115.5 3285.7 3691.2 3703.1 3722.9 5557.9 5584.7 5589.9 5590.2 5593.5 3650.4 3753.4 3767.8 3801.4 3802.6 2961.2 3518.6 3801.1 3840.6 3857.4 3947.2 4416.3 4428.8 4449.3 4458.0 4548.8 5117.8 5122.9 5445.9 5447.0 1022.5 1049.8 1141.7 1171.0 1229.8 760.9 795.4 823.9 843.6 846.8 4072.8 4221.4 4712.3 4716.5 4740.4 4940.6 5012.9 5050.0 5065.7 5101.6 3981.6 4644.7 4679.0 4691.4 4739.4 2214.9 2337.9 2667.7 2682.9 2688.0 4577.0 4792.8 4820.7 4969.6 5267.9 4737.6 4807.0 4839.3 5193.4 5248.9 4120.6 4480.6 4726.4 5204.3 5227.5 4136.6 4213.0 4363.3 5069.9 5114.9 4315.4 4989.3 5055.1 5060.7 5073.7 4226.3 4358.6 4730.6 4864.8 5275.6 3264.8 3496.5 3528.3 3530.2 3589.9 4656.6 4666.9 5027.4 5075.5 5078.7 4213.7 4469.3 4823.2 5099.6 5385.5 4453.6 4713.5 4852.0 5307.3 5325.7 4964.0 5240.3 5242.2 5253.2 5263.6 4466.0 4720.0 4747.5 4817.1 4980.8 4442.4 4966.7 5013.5 5160.2 5362.9 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19695.69 Median:50501.37 stddev:28175.11 (55.8%) stddev-sans-first:28217.94 19:27:15 INFO - PROCESS | 1642 | Values: 672.3 682.9 686.7 742.2 765.1 13949.2 14018.9 14583.8 14645.6 14825.0 12994.7 13134.3 13136.4 13167.4 13184.7 628.7 647.9 680.8 785.4 814.9 815.5 817.2 819.0 820.3 821.9 863.1 863.8 864.0 864.5 865.8 856.5 857.7 859.6 860.5 860.9 633.4 633.6 633.8 635.6 637.8 13491.8 13763.3 13767.6 13806.4 14116.9 13293.4 13321.3 13540.5 13706.2 13871.8 11013.3 11077.7 11285.3 11406.2 11536.1 3552.8 3567.2 3571.4 3659.9 3731.3 85663.0 87936.1 87998.6 89540.0 89789.1 10254.7 10331.9 10365.9 10390.2 10419.2 20376.8 20414.3 20466.1 20471.0 20480.3 7237.6 7464.8 7553.1 7742.1 7801.0 4071.2 4132.8 4158.3 4291.8 4358.3 3330.9 3401.3 3406.3 3474.1 3479.2 636.9 640.3 644.7 655.1 655.6 529.8 625.5 637.3 650.9 660.9 8071.3 8826.4 8839.6 9081.1 9125.0 602.9 641.2 660.3 663.9 669.1 4022.4 4216.7 4219.1 4461.3 4548.2 91154.0 91339.0 94500.5 100834.0 118776.0 73684.2 81824.6 86916.0 88929.0 89555.6 78985.6 81355.0 82446.2 85879.7 88494.0 9176.2 9384.0 9533.8 9546.0 9568.9 46182.6 50486.0 50516.7 53263.0 53935.7 68281.7 68946.7 70587.0 74764.0 76593.0 12253.3 12409.0 12424.4 12439.0 12562.3 12610.6 13535.4 14335.6 14475.6 14673.6 13081.2 13103.9 13195.3 13281.9 13506.1 10965.1 11711.1 13016.7 13328.5 13392.7 12255.2 12293.1 12653.6 12735.5 13276.9 9764.7 9932.1 11426.7 11476.5 11487.9 9322.0 11259.1 11342.8 11345.2 11415.8 19:27:15 INFO - PROCESS | 1642 | -------- Summary: end -------- 19:27:15 INFO - PROCESS | 1642 | 19:27:15 INFO - TEST-INFO | 1642: exit 0 19:27:15 INFO - TEST-OK | dromaeo_css | took 1119266ms 19:27:15 INFO - TEST-START | kraken 19:27:15 INFO - operating with platform_type : mac_ 19:27:15 INFO - Initialising browser for kraken test... 19:27:15 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpFW6CY7/profile http://localhost:49251/getInfo.html) 19:27:18 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 19:27:18 INFO - PROCESS | 1651 | colorDepth:24 19:27:18 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 19:27:18 INFO - PROCESS | 1651 | __metrics 19:27:18 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:27:18 INFO - PROCESS | 1651 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:27:19 INFO - TEST-INFO | 1651: exit 0 19:27:19 INFO - Browser initialized. 19:27:19 INFO - Running cycle 1/1 for kraken test... 19:27:19 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/T/tmpFW6CY7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:31 INFO - PROCESS | 1652 | RSS: Main: 222703616 19:27:31 INFO - PROCESS | 1652 | 19:27:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:35 INFO - PROCESS | 1652 | RSS: Main: 273362944 19:27:35 INFO - PROCESS | 1652 | 19:27:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-dft) 19:27:38 INFO - PROCESS | 1652 | RSS: Main: 285036544 19:27:38 INFO - PROCESS | 1652 | 19:27:41 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-fft) 19:27:42 INFO - PROCESS | 1652 | RSS: Main: 382468096 19:27:42 INFO - PROCESS | 1652 | 19:27:44 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator) 19:27:44 INFO - PROCESS | 1652 | RSS: Main: 283525120 19:27:44 INFO - PROCESS | 1652 | 19:27:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:27:47 INFO - PROCESS | 1652 | RSS: Main: 284864512 19:27:47 INFO - PROCESS | 1652 | 19:27:49 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom) 19:27:50 INFO - PROCESS | 1652 | RSS: Main: 324567040 19:27:50 INFO - PROCESS | 1652 | 19:27:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate) 19:27:54 INFO - PROCESS | 1652 | RSS: Main: 325832704 19:27:54 INFO - PROCESS | 1652 | 19:27:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial) 19:27:57 INFO - PROCESS | 1652 | RSS: Main: 323194880 19:27:57 INFO - PROCESS | 1652 | 19:27:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:27:59 INFO - PROCESS | 1652 | RSS: Main: 299233280 19:27:59 INFO - PROCESS | 1652 | 19:28:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:28:02 INFO - PROCESS | 1652 | RSS: Main: 299978752 19:28:02 INFO - PROCESS | 1652 | 19:28:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:28:04 INFO - PROCESS | 1652 | RSS: Main: 302510080 19:28:04 INFO - PROCESS | 1652 | 19:28:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:28:08 INFO - PROCESS | 1652 | RSS: Main: 316223488 19:28:08 INFO - PROCESS | 1652 | 19:28:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:28:12 INFO - PROCESS | 1652 | RSS: Main: 316801024 19:28:12 INFO - PROCESS | 1652 | 19:28:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49251/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49251/tests/kraken/driver.html?testName=ai-astar) 19:28:14 INFO - PROCESS | 1652 | RSS: Main: 359825408 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | __start_tp_report 19:28:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 19:28:14 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 19:28:14 INFO - PROCESS | 1652 | |i|pagename|runs| 19:28:14 INFO - PROCESS | 1652 | |0;ai-astar;116;115;117;116;116;117;113;115;117;116 19:28:14 INFO - PROCESS | 1652 | |1;audio-beat-detection;145;111;113;157;141;100;130;141;170;111 19:28:14 INFO - PROCESS | 1652 | |2;audio-dft;183;181;183;182;183;182;182;185;185;182 19:28:14 INFO - PROCESS | 1652 | |3;audio-fft;83;59;59;89;61;61;59;58;82;58 19:28:14 INFO - PROCESS | 1652 | |4;audio-oscillator;85;70;95;70;68;70;89;67;69;91 19:28:14 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;87;82;89;83;87;84;88;85;89;85 19:28:14 INFO - PROCESS | 1652 | |6;imaging-darkroom;95;94;93;95;94;95;95;94;95;94 19:28:14 INFO - PROCESS | 1652 | |7;imaging-desaturate;84;80;81;83;84;81;84;83;81;80 19:28:14 INFO - PROCESS | 1652 | |8;json-parse-financial;56;55;55;54;57;59;58;58;57;58 19:28:14 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;65;71;70;77;71;69;69;70;70;71 19:28:14 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;84;84;83;83;83;79;80;84;78;84 19:28:14 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;140;139;140;138;141;142;145;141;212;150 19:28:14 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;161;149;224;226;200;169;169;165;166;165 19:28:14 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;68;66;67;68;67;67;67;67;65;67 19:28:14 INFO - PROCESS | 1652 | __end_tp_report 19:28:14 INFO - PROCESS | 1652 | __start_cc_report 19:28:14 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1987 19:28:14 INFO - PROCESS | 1652 | __end_cc_report 19:28:14 INFO - PROCESS | 1652 | __startTimestamp1483068494102__endTimestamp 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | ------- Summary: start ------- 19:28:14 INFO - PROCESS | 1652 | Number of tests: 14 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:115.80 Median:116.00 stddev:1.23 (1.1%) stddev-sans-first:1.30 19:28:14 INFO - PROCESS | 1652 | Values: 116.0 115.0 117.0 116.0 116.0 117.0 113.0 115.0 117.0 116.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:141.00 stddev:22.78 (16.2%) stddev-sans-first:23.66 19:28:14 INFO - PROCESS | 1652 | Values: 145.0 111.0 113.0 157.0 141.0 100.0 130.0 141.0 170.0 111.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:1.32 (0.7%) stddev-sans-first:1.39 19:28:14 INFO - PROCESS | 1652 | Values: 183.0 181.0 183.0 182.0 183.0 182.0 182.0 185.0 185.0 182.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.90 Median:61.00 stddev:12.43 (20.4%) stddev-sans-first:11.74 19:28:14 INFO - PROCESS | 1652 | Values: 83.0 59.0 59.0 89.0 61.0 61.0 59.0 58.0 82.0 58.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.50 stddev:11.15 (14.4%) stddev-sans-first:11.48 19:28:14 INFO - PROCESS | 1652 | Values: 85.0 70.0 95.0 70.0 68.0 70.0 89.0 67.0 69.0 91.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:2.47 (2.8%) stddev-sans-first:2.59 19:28:14 INFO - PROCESS | 1652 | Values: 87.0 82.0 89.0 83.0 87.0 84.0 88.0 85.0 89.0 85.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:94.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 19:28:14 INFO - PROCESS | 1652 | Values: 95.0 94.0 93.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:82.10 Median:83.00 stddev:1.66 (2.0%) stddev-sans-first:1.62 19:28:14 INFO - PROCESS | 1652 | Values: 84.0 80.0 81.0 83.0 84.0 81.0 84.0 83.0 81.0 80.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.50 stddev:1.64 (2.8%) stddev-sans-first:1.72 19:28:14 INFO - PROCESS | 1652 | Values: 56.0 55.0 55.0 54.0 57.0 59.0 58.0 58.0 57.0 58.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:2.95 (4.2%) stddev-sans-first:2.42 19:28:14 INFO - PROCESS | 1652 | Values: 65.0 71.0 70.0 77.0 71.0 69.0 69.0 70.0 70.0 71.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:82.20 Median:83.50 stddev:2.30 (2.8%) stddev-sans-first:2.35 19:28:14 INFO - PROCESS | 1652 | Values: 84.0 84.0 83.0 83.0 83.0 79.0 80.0 84.0 78.0 84.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:141.50 stddev:22.47 (15.9%) stddev-sans-first:23.61 19:28:14 INFO - PROCESS | 1652 | Values: 140.0 139.0 140.0 138.0 141.0 142.0 145.0 141.0 212.0 150.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:169.00 stddev:27.20 (16.1%) stddev-sans-first:28.02 19:28:14 INFO - PROCESS | 1652 | Values: 161.0 149.0 224.0 226.0 200.0 169.0 169.0 165.0 166.0 165.0 19:28:14 INFO - PROCESS | 1652 | 19:28:14 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.88 (1.3%) stddev-sans-first:0.83 19:28:14 INFO - PROCESS | 1652 | Values: 68.0 66.0 67.0 68.0 67.0 67.0 67.0 67.0 65.0 67.0 19:28:14 INFO - PROCESS | 1652 | -------- Summary: end -------- 19:28:14 INFO - PROCESS | 1652 | 19:28:15 INFO - TEST-INFO | 1652: exit 0 19:28:15 INFO - TEST-OK | kraken | took 59323ms 19:28:15 INFO - SUITE-END | took 1178s 19:28:15 INFO - Completed test suite (00:19:38) 19:28:15 INFO - javascript benchmark 19:28:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3274.607047154341, 3340.098173493157, 3341.0311009807156, 3356.63077061523, 3374.8143852088124, 5660.29246344207, 5671.0, 5745.310562732478, 5754.108113242449, 5791.739371728272, 4814.759262036893, 5629.690367029812, 5653.6607803531815, 5727.828165155052, 5728.16941543476, 5207.869803254911, 5209.270702101709, 5230.058589453897, 5283.920741188886, 5311.309529761125, 5164.0, 5187.221916712486, 5217.321748172732, 5239.764210610531, 5326.360836699582, 5196.844094677147, 5298.682079075248, 5304.389995150549, 5312.57499400049, 5337.893242135175, 5274.2879711238875, 5285.709285989269, 5294.549963253128, 5299.178627312773, 5335.466453354672, 4364.148990946766, 5108.310378098983, 5134.691918484903, 5175.275461435402, 5218.165093585008, 5625.8593535161535, 5683.744231509592, 5699.173619825144, 5721.998650236192, 5722.399148089474, 6130.929516759049, 6179.721912513903, 6192.752289908398, 6195.0707393890825, 6200.968995155041, 6158.8768224635705, 6220.253569571681, 6220.377962203788, 6226.6264024158245, 6231.470325022333, 5734.713264336767, 5762.049261871786, 5762.30852297727, 5828.300603927556, 5867.970660146715, 6339.683015849189, 6346.619202847844, 6350.0792385103905, 6354.3963323484195, 6356.968215158987, 5974.2830860297045, 6028.125921741323, 6053.303870055, 6191.19514463117, 6206.968965155235, 6174.969125154345, 6178.598391104564, 6248.094026366151, 6299.338569450142, 6300.243970723543, 6243.562950593415, 6248.9062664060075, 6264.404881536247, 6269.435750782452, 6277.529185311028, 6120.296165940885, 6146.862830376394, 6171.59884607499, 6248.75004999804, 6272.717727702265, 4896.387951506062, 4922.409310882717, 4947.356843610308, 4947.876303092451, 4950.381202349707, 4417.315316126005, 4427.424434823452, 4445.022095139064, 4460.8661740147845, 4466.285394336891, 5709.257796486513, 5798.594098413155, 5800.9129863052085, 5812.21535092757, 5835.095560188178, 6131.101423540749, 6173.69131543421, 6176.567640265138, 6194.566380353423, 6258.249010118815, 5769.221155144086, 5842.649441033552, 5872.236609240771, 5887.2052272943465, 5891.734871930774, 5414.052540805344, 5420.078586640202, 5426.728663566806, 5426.9185962210595, 5427.402985671573, 6128.564234752123, 6273.52948528853, 6392.26488953767, 6406.967965160145, 6411.551191416556, 6155.446009859134, 6182.783602573888, 6213.65824879636, 6219.968900155471, 6278.623282603058, 5151.922721159261, 6380.93619063822, 6396.712147953446, 6411.903821442776, 6448.129502517288, 6186.19579454668, 6224.066390041349, 6226.0, 6248.312685604671, 6248.718807653576, 6110.862540392742, 6145.231846019247, 6163.691815409301, 6224.751009959732, 6248.4376406123665, 6121.265448146251, 6157.599756015845, 6158.107074474266, 6158.9221886170635, 6164.229471316085, 6124.295705993868, 6143.416375444325, 6151.876962460637, 6153.907691384543, 6162.537809664203, 5652.689102099424, 5715.599908006399, 5871.236739223874, 5890.823275301748, 5910.379410161871, 4648.883777905579, 5786.103154011135, 6124.686092398567, 6138.419052470106, 6162.322144564005, 5755.482006619424, 5820.068788993741, 5976.7908123215475, 5997.430244126976, 5999.580029398075], "value": 5690.612634960691, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.48613684836204, 504.3745755263472, 508.2224196978623, 515.7369741431869, 546.2461802712256, 30885.691143088563, 40780.59219407809, 41912.95217619561, 42018.15963680724, 42126.789366053126, 42739.0, 42848.57151428485, 42937.570624293745, 43019.78490107553, 43030.0, 9393.812123757521, 9564.808703825955, 9576.329656924017, 9579.089986451276, 9596.32825702201, 9409.247260219201, 9529.951705312411, 9534.332596718232, 9536.713898583053, 9544.522773861314, 9489.335746497747, 9556.522173891277, 9560.282978776584, 9564.091411315914, 9570.186534144572, 9475.147236748691, 9550.188234000154, 9554.140127388568, 9567.473788941605, 9568.234541236685, 9367.765805854908, 9398.38910470824, 9438.90561094394, 9442.291828112919, 9455.858162127504, 39541.01147471307, 39883.40174897379, 39930.001749956486, 39962.80018599889, 40019.0, 3205.8717651294155, 3246.4156451838658, 3310.9834450827834, 3688.2112175594816, 3822.311983842907, 28702.282442939093, 28745.0, 30347.696523034927, 31033.844830775928, 31238.0, 3163.6678148794276, 3183.522471629251, 3198.74410047198, 3250.577424934767, 3275.410426123297, 432216.0, 432528.0, 435907.82046089886, 435968.0, 452434.0, 33246.66753332484, 33849.492257616155, 34233.657663423044, 34913.0, 59566.404335956955, 30113.247168820955, 35817.0, 35993.64006359903, 36028.81985590108, 36108.45837312443, 14341.426342946374, 14464.20446875432, 14493.637659058608, 14501.129932204101, 14526.346314415876, 12231.0, 12275.079369047357, 12317.0, 12360.629181124581, 12362.196457230251, 7823.530588164728, 7832.765017049498, 7848.372130229568, 7892.763217103496, 7935.08746494149, 3206.374756922378, 3208.262099717078, 3213.694699003591, 3224.774265801418, 3263.184203949013, 944.263474489899, 990.3760630802549, 1024.349538043333, 1034.4103860799273, 1059.184427990443, 29581.0, 29730.25674358115, 30123.548146777815, 30348.84825575902, 30624.387512250076, 902.9909700902907, 916.0656130746601, 953.4327075390132, 987.6790043235977, 1092.5083712329483, 15032.398704051715, 15534.67955223796, 15552.611184720246, 15897.841021589868, 15908.920455397649, 667238.0, 749336.0, 756554.0, 760361.1981940055, 953700.0, 59099.81800364055, 59626.70186649126, 59743.70128149331, 59768.70115649394, 60112.69943650254, 1161.1233518693332, 1161.1523587781137, 1198.2750435986102, 1353.9323033848268, 1378.6139880833382, 22931.0, 30724.385512290075, 31393.372132557677, 31496.89760858359, 32263.0, 56045.15932261101, 56588.0, 56601.0, 56658.58353541195, 56678.14982775179, 580307.0984645049, 583591.0, 585326.0, 589064.0, 590166.0, 26888.59667104956, 27427.588586170823, 35524.467132993545, 36207.818960905024, 36230.818845905604, 36708.632913670524, 37969.24061518809, 39525.01187470336, 47450.0, 49328.50671493239, 37002.814985924895, 39454.40818387784, 39734.0, 40893.0, 42021.579784201764, 38740.225195496496, 38795.806020969714, 39096.80451597724, 40383.76929845547, 40404.393934090425, 37603.81198094101, 38269.0, 38294.042648934, 38404.0, 40024.799256022365, 31833.8858139964, 32359.838200808845, 34037.82981085079, 35078.12304692403, 35103.82448087743, 20032.44200742394, 25772.742272577034, 25787.96848126129, 26196.8690156548, 26311.60532592051], "value": 18536.684824112246, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.86533223380064, 369.09387453800923, 369.6709928163934, 392.74864086984354, 405.44251653975783, 19214.807851921494, 19806.009699515038, 19993.400197994084, 20679.0, 20772.792272077273, 19999.000049997514, 20264.493387665316, 20595.38213853583, 20717.896410517926, 20875.478113047182, 5807.30312362516, 5912.497437717778, 5965.552583556229, 6012.5791194616395, 6089.9342615042415, 4173.4157217989505, 5929.970350148244, 6041.275046994367, 6043.274807023164, 6043.667598282114, 4382.430284063067, 4391.670624703144, 4424.40270563473, 4434.068845542424, 6655.800325990203, 6463.030545418178, 6696.698648560802, 6717.899231511531, 6720.092787473702, 6726.630035348054, 5598.300212473441, 5809.390014048548, 5865.325487568942, 5867.178594996704, 5881.3530511643685, 23686.526269474685, 25561.744382556124, 25691.743082569123, 25801.741982580123, 25985.0, 2215.1582398688556, 2216.4015715756655, 2244.764299748519, 2255.5037891663806, 2312.2369618026178, 5346.572274218053, 5634.577406694473, 7211.675474603603, 10407.115395191418, 10719.410432426292, 1820.6085691576375, 1856.9535761605932, 1857.851371890259, 1859.4514618187668, 1908.6564418404682, 89925.65111523333, 127480.0, 128456.0, 129867.0, 131595.0, 9468.715938521855, 11497.195196336175, 11740.471678774305, 11788.88211117895, 11927.224730392496, 15109.773353399707, 15143.015703979207, 15153.924230378778, 15165.393384264726, 15172.468963586442, 8352.624131914079, 8571.357148213909, 8589.14108589136, 8722.215000649972, 8771.736847894446, 8409.705660301861, 8411.747647570583, 8422.915770842214, 8534.87197692035, 8559.058503564604, 4541.841035563805, 4682.836100736458, 4698.694584851974, 4701.64737644678, 4734.881627959259, 1852.8054554271878, 1913.9712904306446, 1920.7310976463307, 1921.1258877210835, 1923.7210604462555, 758.5751978891839, 863.9395242333102, 883.4169448164181, 915.706973768401, 979.7991411760572, 20663.58672826535, 21703.914804259723, 21954.560908781736, 22115.889420552794, 22117.115315387204, 876.7019213467452, 942.5240253671852, 943.3444852739516, 950.1733491861986, 959.8800149981253, 8916.509591972504, 10883.455827208609, 11256.437178141061, 11285.435728213557, 11399.20205585601, 593264.0, 609068.0, 614779.0, 647872.0, 647899.0, 101722.0, 125300.0, 128475.07287390696, 135861.0, 136438.0, 944.3955868244329, 967.2793768642406, 995.8954309797367, 1019.8623185869961, 1021.1830535571513, 19310.131044102767, 20040.797552146916, 20439.795602044385, 20449.897750511747, 20467.181312747325, 30510.542341864446, 48783.512164877895, 56022.71988640031, 56222.0, 56962.71518642546, 80214.0, 81383.5930820342, 81533.0, 82494.98792602697, 82782.58608706918, 7420.962895185706, 8194.180581941997, 8387.203215694499, 8429.4942303462, 8489.490630562186, 11290.322580645188, 13153.671158221121, 13153.868461315264, 13462.586428424875, 13725.656858578615, 10911.127109831035, 11255.437228138397, 11378.601748939078, 11474.541018359172, 11727.824082638597, 10964.969008367078, 11326.716832079264, 11376.54493820238, 11608.709782255512, 11725.0, 11378.54485820558, 11599.304041757521, 11680.240784348991, 11832.46753896094, 11863.347515886364, 8195.60674685293, 8355.206255405727, 8420.957895210484, 8646.005709343255, 8686.47881127109, 8831.514266715392, 11273.379964102052, 11284.21010529255, 11387.544498219979, 11817.645470635895], "value": 9557.396605767164, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1269.5048930916944, 1435.1604311477781, 1554.4481708993308, 1748.3531093495408, 1802.8377446029847, 8082.474639148459, 8421.073681894994, 8425.36809739269, 8425.915740842589, 8454.915450845481, 7516.699332026712, 7766.029246344207, 7787.532748035108, 8132.308753755938, 8208.425410221287, 2333.288347054148, 2372.4543355028354, 2372.8101751859895, 2375.596148654733, 2379.0721618568773, 2352.5530149271585, 2375.8930848111877, 2391.3065211088765, 2405.6391541268777, 2407.374082738492, 1987.6124155789555, 2347.049444974788, 2371.6679664846934, 2377.785999260066, 2379.773921477457, 2175.5431359414542, 2326.499802542453, 2339.9181028663916, 2343.7304709958403, 2350.471143992588, 2163.4266919266406, 2378.5599664062206, 2386.05750728463, 2389.211560185152, 2390.521895620883, 8363.247307742333, 8400.621972011213, 8455.53494557799, 8470.745877623682, 8475.152484751537, 4734.147853386389, 5073.492650734934, 5082.669626474304, 5247.291615631917, 5257.684538927677, 2888.5667149927467, 3281.737461003114, 3623.5108260385036, 3630.0561853917907, 3634.8182590870615, 1052.3273774526922, 1052.7473406382412, 1062.4793851012948, 1089.4607169451094, 1095.386583513235, 10797.190210734227, 10929.672109836718, 10952.069074128629, 10962.068224200873, 10963.890361096446, 4541.545845415432, 4672.766361681903, 4780.880477988008, 4801.087793319258, 4805.735684537384, 9476.905230947741, 9478.763030924145, 9482.762930926781, 9483.952580237055, 9500.667476638284, 5204.297419848272, 5213.113770658997, 5222.0, 5223.451537588575, 5234.738263086831, 4063.6139566741113, 4225.345071513921, 4779.402574678165, 4794.232922732346, 4831.95168048322, 3285.227971426713, 3810.466534685146, 3830.7893065881085, 3886.397608370707, 3927.8232479538487, 1086.2124959404528, 1251.7997120460682, 1255.453877563263, 1260.1053252190864, 1271.7774389481804, 757.7575175943755, 860.337540094124, 900.243795212025, 900.9954950225207, 903.408267584733, 4797.904041919142, 5624.846906384181, 5678.091505359205, 5979.402059793986, 6006.489448396847, 970.5875003123786, 998.8601595776597, 1011.7217765114506, 1024.293237666008, 1044.2951008069522, 3775.679067279312, 3835.9232815343535, 3845.288621605012, 3932.9577661919616, 3968.4840970674204, 9870.802583948282, 10323.793524129474, 10330.48347582633, 10342.948285258526, 10346.844797328045, 10251.794964100676, 10277.640282590073, 10286.537105830255, 10292.073713365833, 10299.794004119876, 7577.772666820004, 7815.0231221097365, 7848.960755196187, 7937.920620793718, 7992.3606111510935, 5510.504054635102, 5572.3034620672415, 5650.519705825051, 5685.204071430086, 5727.198192253005, 8257.339412847077, 9350.298727595322, 9534.0, 9590.232781377612, 9592.616295348387, 9549.522523873917, 9582.425054496753, 9654.372465789702, 9659.613615455584, 9697.981711920147, 5759.0, 5999.49004334619, 6466.51501137426, 6753.425958793558, 6902.654867256718, 7756.185600511865, 7936.2857342839425, 7986.840263194587, 8039.758807235792, 8045.195480451908, 7136.500444968802, 7227.0, 7253.347198752138, 7262.636868156676, 7381.483296169216, 7035.155781306276, 7095.25950347162, 7124.501284910007, 7220.0, 7280.017197678246, 6665.366790154928, 6667.566608170648, 6728.259891412039, 6744.123263975652, 6756.256811750605, 4885.95114048855, 4897.9020419592125, 4910.729909854992, 4912.45962944083, 4948.579370753526, 4111.732737372061, 4667.8599642010795, 4746.38297021385, 4799.520047995173, 4919.606431485401], "value": 4432.700268606183, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.17851818240746, 301.83248297195047, 306.55549453292724, 313.1919647309941, 314.82841851191097, 4962.848591144622, 5125.69245845249, 5154.938024790086, 5171.224316352549, 5177.922331165036, 4945.3323801286815, 5005.049040682268, 5072.148665737007, 5081.28861959326, 5108.386993560778, 3677.650623190806, 3951.466552015484, 3956.4460975463458, 3960.8613698520558, 3963.504561929766, 3805.600411956746, 3969.702272329572, 3971.900702482447, 3973.4239184733683, 4007.499062617173, 3904.609539046101, 3908.1011367385518, 3922.293987082324, 3932.4297976793346, 3961.564227934925, 3921.2353591049614, 3922.4508568800393, 3925.156091440354, 3927.8821635350987, 3934.488516492838, 2930.0477344862793, 2982.4929761940307, 3360.2271477560053, 3371.0, 3458.4466485362473, 4347.0, 4935.235038569028, 4964.180910149821, 4980.352554167971, 4999.8250061248045, 3690.852365905361, 3994.6018893387372, 4130.6282434581035, 4399.824007039714, 4403.244495944405, 4854.029194161147, 4897.363342765418, 4953.504649535053, 5281.8151364702435, 5342.652727572696, 3115.4547954108166, 3285.720713739335, 3691.2248427830186, 3703.092742278132, 3722.9069273268383, 5557.888842223173, 5584.720763961785, 5589.916151257735, 5590.161475778625, 5593.496585307342, 3650.361186792301, 3753.418220175877, 3767.811609419518, 3801.4487899254455, 3802.5817160112365, 2961.2448825549777, 3518.5777706675362, 3801.087738942689, 3840.558335791364, 3857.363535016747, 3947.1513624570857, 4416.315471101984, 4428.800703968264, 4449.310356894686, 4457.955420445754, 4548.840790572314, 5117.846464605993, 5122.92315615266, 5445.918311225256, 5446.972765136149, 1022.4744880146027, 1049.8267785815256, 1141.6917432293235, 1171.0, 1229.846269216348, 760.9384908053235, 795.3716563914559, 823.8671826238922, 843.5571325054395, 846.8221673448521, 4072.780334717527, 4221.395869569544, 4712.340272361872, 4716.457607375127, 4740.360051393087, 4940.629452791055, 5012.872103776694, 5049.974750126225, 5065.696058236444, 5101.566366858784, 3981.6018398159954, 4644.674872758942, 4678.994016286515, 4691.366665500182, 4739.431268247763, 2214.867107973527, 2337.8842147856217, 2667.6798784146026, 2682.9329266768486, 2688.0, 4577.038821847449, 4792.825757689388, 4820.734859582757, 4969.627277954096, 5267.945501254514, 4737.620990320766, 4806.975965120152, 4839.346688197048, 5193.3767947845745, 5248.921266181011, 4120.608542188487, 4480.647659978537, 4726.385569875894, 5204.308599705036, 5227.536289838851, 4136.648384887319, 4213.0, 4363.3018717005125, 5069.898602028013, 5114.897702045864, 4315.374270730664, 4989.2516122580455, 5055.090083784954, 5060.661002642248, 5073.670211436245, 4226.344916537942, 4358.564143585743, 4730.6215502759005, 4864.781084851119, 5275.604329675367, 3264.79626252274, 3496.461556914949, 3528.2943411317324, 3530.223350862806, 3589.869191204762, 4656.627469802475, 4666.923219167438, 5027.3551675035305, 5075.462268865567, 5078.669886457368, 4213.747175169499, 4469.284914413678, 4823.204171311834, 5099.603186502338, 5385.515303622693, 4453.617314736601, 4713.457952335525, 4851.975740121276, 5307.310049693515, 5325.707086110301, 4963.950360496493, 5240.318758561362, 5242.213667949838, 5253.185756207794, 5263.578913686819, 4465.995151091109, 4719.976400118115, 4747.548982846624, 4817.084753896748, 4980.800767969386, 4442.3558584004495, 4966.652334336706, 5013.498650135103, 5160.193932123727, 5362.865928351823], "value": 3761.464571908805, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.320955834607, 682.8702546516162, 686.6738299307826, 742.2206682982867, 765.1162906842595, 13949.163050216994, 14018.878489720822, 14583.781243281333, 14645.633859153526, 14825.0, 12994.74010519789, 13134.277614731185, 13136.408861601227, 13167.407466663999, 13184.670383240424, 628.6950828848016, 647.9157709497758, 680.8468094678707, 785.4305628419407, 814.8899898513714, 815.5351449673689, 817.2236375443323, 819.0130892274794, 820.3232333325001, 821.9383546234027, 863.0678866823847, 863.8315528471951, 864.0, 864.5072308783964, 865.7662431143588, 856.5417501636657, 857.7469646454311, 859.6217664227751, 860.5138096975189, 860.9268212201939, 633.3571425003626, 633.5786701843268, 633.822529691687, 635.6345101566618, 637.8309747916805, 13491.797623035662, 13763.31183440824, 13767.586972390744, 13806.447742090304, 14116.85883141176, 13293.401796919163, 13321.333933303393, 13540.458381664721, 13706.21323201156, 13871.791923121162, 11013.2841365311, 11077.667669969915, 11285.322880627189, 11406.201565890391, 11536.077113830874, 3552.8045957472586, 3567.161716996504, 3571.4285714285847, 3659.9085022874374, 3731.3470142724914, 85663.0, 87936.12063879344, 87998.5600072002, 89540.0, 89789.10210897872, 10254.692359229235, 10331.896681033093, 10365.896341036643, 10390.168786497063, 10419.218558607983, 20376.796232037785, 20414.285500007725, 20466.079026443847, 20471.0, 20480.283190088274, 7237.565746055254, 7464.813379665552, 7553.1313898901235, 7742.109657389472, 7801.0, 4071.206114807643, 4132.834686612502, 4158.313878210061, 4291.785410729451, 4358.259095953696, 3330.900072997814, 3401.2517246205794, 3406.318736252759, 3474.0793689672255, 3479.1650003998857, 636.9108324834527, 640.2636967487334, 644.7485480662546, 655.1450357283695, 655.6426747422666, 529.7960285290114, 625.4683519008806, 637.3371693438864, 650.8730797494538, 660.92729799722, 8071.313938315191, 8826.42628229163, 8839.558022098869, 9081.137291957253, 9124.954375228213, 602.9125776762345, 641.2497378067673, 660.2901880478505, 663.8639078988796, 669.0833558025536, 4022.416749571354, 4216.7259128156575, 4219.113986062961, 4461.263891457938, 4548.158590660738, 91154.0, 91339.0, 94500.52749736345, 100834.0, 118776.0, 73684.21052631669, 81824.59087704524, 86916.0, 88929.0, 89555.55222223848, 78985.60507197428, 81355.0, 82446.17553824386, 85879.71180432424, 88494.0, 9176.174144327042, 9383.953080234554, 9533.809323813346, 9546.0, 9568.856467152858, 46182.61452156441, 50486.0, 50516.74741626268, 53263.0, 53935.73032134814, 68281.65859170673, 68946.65526672335, 70587.0, 74764.0, 76593.0, 12253.32606706604, 12409.0, 12424.446944131983, 12439.0, 12562.309073001074, 12610.58308122447, 13535.351527559384, 14335.56993290203, 14475.638109047359, 14673.62559501821, 13081.215127092404, 13103.934480327538, 13195.305458873108, 13281.933590331986, 13506.054576179966, 10965.067969222706, 11711.063114950613, 13016.739665206453, 13328.466861325438, 13392.665183370103, 12255.203411778206, 12293.078019148421, 12653.620391388273, 12735.490580376681, 13276.867231327564, 9764.707058788248, 9932.106110450095, 11426.657200284004, 11476.540938362372, 11487.885121148682, 9321.953390233006, 11259.09927205838, 11342.773144537228, 11345.205835591429, 11415.828762568734], "value": 7153.5440580453205, "unit": "score"}], "name": "dromaeo_css", "value": 7025.6679514576745, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 117.0, 116.0, 116.0, 117.0, 113.0, 115.0, 117.0, 116.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 113.0, 157.0, 141.0, 100.0, 130.0, 141.0, 170.0, 111.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 183.0, 182.0, 183.0, 182.0, 182.0, 185.0, 185.0, 182.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 89.0, 61.0, 61.0, 59.0, 58.0, 82.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 95.0, 70.0, 68.0, 70.0, 89.0, 67.0, 69.0, 91.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 89.0, 83.0, 87.0, 84.0, 88.0, 85.0, 89.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 93.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 81.0, 83.0, 84.0, 81.0, 84.0, 83.0, 81.0, 80.0], "value": 82.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 54.0, 57.0, 59.0, 58.0, 58.0, 57.0, 58.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 70.0, 77.0, 71.0, 69.0, 69.0, 70.0, 70.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 83.0, 83.0, 79.0, 80.0, 84.0, 78.0, 84.0], "value": 82.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 140.0, 138.0, 141.0, 142.0, 145.0, 141.0, 212.0, 150.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 149.0, 224.0, 226.0, 200.0, 169.0, 169.0, 165.0, 166.0, 165.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 65.0, 67.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1441.5, "alertThreshold": 2.0}]} 19:28:15 INFO - Return code: 0 19:28:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:28:15 INFO - # TBPL SUCCESS # 19:28:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:28:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:28:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:28:15 INFO - 2016-12-29 19:28:15.439 screenresolution[1656:9706] starting screenresolution argv=screenresolution get 19:28:15 INFO - 2016-12-29 19:28:15.442 screenresolution[1656:9706] Display 0: 1600x1200x32@60 19:28:15 INFO - 2016-12-29 19:28:15.448 screenresolution[1657:9709] starting screenresolution argv=screenresolution list 19:28:15 INFO - Available Modes on Display 0 19:28:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:28:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:28:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:28:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:28:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:28:15 INFO - Intel Iris: 19:28:15 INFO - Chipset Model: Intel Iris 19:28:15 INFO - Type: GPU 19:28:15 INFO - Bus: Built-In 19:28:15 INFO - VRAM (Dynamic, Max): 1536 MB 19:28:15 INFO - Vendor: Intel (0x8086) 19:28:15 INFO - Device ID: 0x0a2e 19:28:15 INFO - Revision ID: 0x0009 19:28:15 INFO - Displays: 19:28:15 INFO - Display: 19:28:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:28:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:28:15 INFO - Display Serial Number: 1600x1200 60 19:28:15 INFO - Main Display: Yes 19:28:15 INFO - Mirror: Off 19:28:15 INFO - Online: Yes 19:28:15 INFO - Rotation: Supported 19:28:15 INFO - Return code: 0 19:28:15 INFO - Running post-action listener: _resource_record_post_action 19:28:15 INFO - [mozharness: 2016-12-30 03:28:15.691157Z] Finished run-tests step (success) 19:28:15 INFO - Running post-run listener: _resource_record_post_run 19:28:15 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38781952; Write bytes: 658703360; Read time: 382; Write time: 1822 19:28:15 INFO - TinderboxPrint: CPU usage
53.4% 19:28:15 INFO - TinderboxPrint: I/O read bytes / time
38,781,952 / 382 19:28:15 INFO - TinderboxPrint: I/O write bytes / time
658,703,360 / 1,822 19:28:15 INFO - TinderboxPrint: CPU idle
2,258.9 (46.6%) 19:28:15 INFO - TinderboxPrint: CPU system
970.4 (20.0%) 19:28:15 INFO - TinderboxPrint: CPU user
1,616.4 (33.4%) 19:28:15 INFO - TinderboxPrint: Swap in / out
387,096,576 / 0 19:28:15 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 350099968; Write bytes: 352138752; Read time: 27889; Write time: 676 19:28:15 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 34821120; Write bytes: 305229312; Read time: 352; Write time: 1142 19:28:16 INFO - Running post-run listener: _upload_blobber_files 19:28:16 INFO - Blob upload gear active. 19:28:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:28:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:28:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:28:16 INFO - (blobuploader) - INFO - Open directory for files ... 19:28:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:28:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:28:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:17 INFO - (blobuploader) - INFO - Done attempting. 19:28:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:28:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:28:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:18 INFO - (blobuploader) - INFO - Done attempting. 19:28:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:28:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:28:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:28:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:20 INFO - (blobuploader) - INFO - Done attempting. 19:28:20 INFO - (blobuploader) - INFO - Iteration through files over. 19:28:20 INFO - Return code: 0 19:28:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:28:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:28:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85a7108125443095db206a280178884593877bf8f616e6630e42d84183cd1ac99b820587b1312ec64f930cad279f1bb599dabd25afa7ffa01f908fbf614a2bac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d38903b9a313caf31c1e5bb0ccaa37cbf089a9ddc3687dda7abb898b3c827ed2c282369a515267100edc71de0b5a57df75058be1a2a95a1c37429ccda57c53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c705fd6c56595fe724c3fec9e68e0380c68efef16147c104d1b57369785a35969588412c20a664661360b9e5bbef02d8f077c7838279b79ae90b4d32cfa843f"} 19:28:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:28:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:28:20 INFO - Contents: 19:28:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85a7108125443095db206a280178884593877bf8f616e6630e42d84183cd1ac99b820587b1312ec64f930cad279f1bb599dabd25afa7ffa01f908fbf614a2bac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d38903b9a313caf31c1e5bb0ccaa37cbf089a9ddc3687dda7abb898b3c827ed2c282369a515267100edc71de0b5a57df75058be1a2a95a1c37429ccda57c53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c705fd6c56595fe724c3fec9e68e0380c68efef16147c104d1b57369785a35969588412c20a664661360b9e5bbef02d8f077c7838279b79ae90b4d32cfa843f"} 19:28:20 INFO - Running post-run listener: copy_logs_to_upload_dir 19:28:20 INFO - Copying logs to upload dir... 19:28:20 INFO - mkdir: /builds/slave/test/build/upload/logs 19:28:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.482358 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-29 19:28:20.840879) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 19:28:20.845107) ========= 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.gucLwZaERP/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.EpYcDMNlmG/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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/85a7108125443095db206a280178884593877bf8f616e6630e42d84183cd1ac99b820587b1312ec64f930cad279f1bb599dabd25afa7ffa01f908fbf614a2bac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d38903b9a313caf31c1e5bb0ccaa37cbf089a9ddc3687dda7abb898b3c827ed2c282369a515267100edc71de0b5a57df75058be1a2a95a1c37429ccda57c53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c705fd6c56595fe724c3fec9e68e0380c68efef16147c104d1b57369785a35969588412c20a664661360b9e5bbef02d8f077c7838279b79ae90b4d32cfa843f"} build_url:https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011031 build_url: 'https://queue.taskcluster.net/v1/task/aQvBFDcjRcmCl_A7jryjYw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85a7108125443095db206a280178884593877bf8f616e6630e42d84183cd1ac99b820587b1312ec64f930cad279f1bb599dabd25afa7ffa01f908fbf614a2bac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0d38903b9a313caf31c1e5bb0ccaa37cbf089a9ddc3687dda7abb898b3c827ed2c282369a515267100edc71de0b5a57df75058be1a2a95a1c37429ccda57c53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c705fd6c56595fe724c3fec9e68e0380c68efef16147c104d1b57369785a35969588412c20a664661360b9e5bbef02d8f077c7838279b79ae90b4d32cfa843f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 19:28:20.883591) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:28:20.883997) ========= 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.gucLwZaERP/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.EpYcDMNlmG/Listeners TMPDIR=/var/folders/js/rwfvb6856vj5t4gxv45w5yv800000w/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.004996 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 19:28:20.933986) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 19:28:20.934357) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 19:28:20.934796) ========= ========= Total master_lag: 0.20 =========