builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1479145685.06 results: success (0) buildid: 20161114043447 builduid: b3ce9a064b684e8d999f5a98ceef2059 revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.058087) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.058782) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.059276) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.086215) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.086708) ========= 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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-11-14 09:48:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2016-11-14 09:48:05 (15.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.258549 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.368814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.369126) ========= 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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.027230 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:05.417168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-14 09:48:05.417498) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --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 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QhvtmhAFNa/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.7a2W4mDRnB/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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-11-14 09:48:05,495 truncating revision to first 12 chars 2016-11-14 09:48:05,496 Setting DEBUG logging. 2016-11-14 09:48:05,496 attempt 1/10 2016-11-14 09:48:05,496 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7e29173b1641?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 09:48:07,319 unpacking tar archive at: mozilla-aurora-7e29173b1641/testing/mozharness/ program finished with exit code 0 elapsedTime=2.080820 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-14 09:48:07.526004) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:07.526476) ========= script_repo_revision: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:07.527556) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:07.527972) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 09:48:07.547066) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 09:48:07.547361) ========= /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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48:07 INFO - MultiFileLogger online at 20161114 09:48:07 in /builds/slave/test 09:48:07 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 09:48:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:07 INFO - {'append_to_log': False, 09:48:07 INFO - 'base_work_dir': '/builds/slave/test', 09:48:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:48:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:07 INFO - 'branch': 'Mozilla-Aurora', 09:48:07 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:07 INFO - 'config_files': ('talos/mac_config.py',), 09:48:07 INFO - 'default_actions': ('clobber', 09:48:07 INFO - 'read-buildbot-config', 09:48:07 INFO - 'download-and-extract', 09:48:07 INFO - 'populate-webroot', 09:48:07 INFO - 'create-virtualenv', 09:48:07 INFO - 'install', 09:48:07 INFO - 'run-tests'), 09:48:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:07 INFO - 'download_minidump_stackwalk': True, 09:48:07 INFO - 'download_symbols': 'ondemand', 09:48:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:07 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:07 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:07 INFO - 'installer_path': 'installer.exe', 09:48:07 INFO - 'log_level': 'info', 09:48:07 INFO - 'log_name': 'talos', 09:48:07 INFO - 'log_to_console': True, 09:48:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:48:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:48:07 INFO - 'opt_config_files': (), 09:48:07 INFO - 'pip_index': False, 09:48:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:48:07 INFO - 'cmd': ('bash', 09:48:07 INFO - '-c', 09:48:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:48:07 INFO - 'enabled': True, 09:48:07 INFO - 'halt_on_failure': False, 09:48:07 INFO - 'name': 'check_screen_resolution'},), 09:48:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:48:07 INFO - 'cmd': ('bash', 09:48:07 INFO - '-c', 09:48:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:48:07 INFO - 'enabled': True, 09:48:07 INFO - 'halt_on_failure': False, 09:48:07 INFO - 'name': 'check_screen_resolution'},), 09:48:07 INFO - 'run_cmd_checks_enabled': True, 09:48:07 INFO - 'sps_profile': False, 09:48:07 INFO - 'sps_profile_interval': 0, 09:48:07 INFO - 'suite': 'dromaeojs', 09:48:07 INFO - 'system_bits': '32', 09:48:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:07 INFO - 'title': 't-yosemite-r7-0131', 09:48:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:07 INFO - 'use_talos_json': True, 09:48:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:07 INFO - 'work_dir': 'build'} 09:48:07 INFO - [mozharness: 2016-11-14 17:48:07.756418Z] Running clobber step. 09:48:07 INFO - Running pre-action listener: _resource_record_pre_action 09:48:07 INFO - Running main action method: clobber 09:48:07 INFO - rmtree: /builds/slave/test/build 09:48:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:10 INFO - Running post-action listener: _resource_record_post_action 09:48:10 INFO - [mozharness: 2016-11-14 17:48:10.258637Z] Finished clobber step (success) 09:48:10 INFO - [mozharness: 2016-11-14 17:48:10.258876Z] Running read-buildbot-config step. 09:48:10 INFO - Running pre-action listener: _resource_record_pre_action 09:48:10 INFO - Running main action method: read_buildbot_config 09:48:10 INFO - Using buildbot properties: 09:48:10 INFO - { 09:48:10 INFO - "project": "", 09:48:10 INFO - "product": "firefox", 09:48:10 INFO - "script_repo_revision": "production", 09:48:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:48:10 INFO - "repository": "", 09:48:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:48:10 INFO - "buildid": "20161114043447", 09:48:10 INFO - "pgo_build": "False", 09:48:10 INFO - "basedir": "/builds/slave/test", 09:48:10 INFO - "buildnumber": 23, 09:48:10 INFO - "slavename": "t-yosemite-r7-0131", 09:48:10 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:48:10 INFO - "platform": "yosemite_r7", 09:48:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:48:10 INFO - "branch": "mozilla-aurora", 09:48:10 INFO - "repo_path": "releases/mozilla-aurora", 09:48:10 INFO - "revision": "7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f", 09:48:10 INFO - "stage_platform": "macosx64", 09:48:10 INFO - "builduid": "b3ce9a064b684e8d999f5a98ceef2059", 09:48:10 INFO - "slavebuilddir": "test" 09:48:10 INFO - } 09:48:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:48:10 INFO - Running post-action listener: _resource_record_post_action 09:48:10 INFO - [mozharness: 2016-11-14 17:48:10.264529Z] Finished read-buildbot-config step (success) 09:48:10 INFO - [mozharness: 2016-11-14 17:48:10.264739Z] Running download-and-extract step. 09:48:10 INFO - Running pre-action listener: _resource_record_pre_action 09:48:10 INFO - Running main action method: download_and_extract 09:48:10 INFO - mkdir: /builds/slave/test/build/tests 09:48:10 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')]} 09:48:10 INFO - trying https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:48:10 INFO - Downloading https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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 09:48:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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 09:48:11 INFO - Downloaded 1199 bytes. 09:48:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:48:11 INFO - Using the following test package requirements: 09:48:11 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:48:11 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:48:11 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:48:11 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'jsshell-mac.zip'], 09:48:11 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:48:11 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:48:11 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:48:11 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:48:11 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:48:11 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:11 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:48:11 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:48:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:48:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:48:11 INFO - Fetch https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:48:12 INFO - Http code: 200 09:48:12 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 09:48:12 INFO - x-amz-cf-id: OxFJbd7oX1t9H3RgN__wiGJzPa8fHRDn-ZM9W4Pmkl9Ucn3IehzCXg== 09:48:12 INFO - x-amz-version-id: 46b3rovJZlUGpegJJyUApBTnVWK4pxSj 09:48:12 INFO - Expected file size: 16327493 09:48:12 INFO - Obtained file size: 16327493 09:48:19 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 09:48:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:48:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:48:19 INFO - Fetch https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:48:19 INFO - Http code: 200 09:48:19 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 09:48:19 INFO - x-amz-cf-id: fc0vFbc9WgY9JYvdcLun4gaMZSQlPP7DKZLl_uxEz2v6Z4TMX9wRHg== 09:48:19 INFO - x-amz-version-id: 46b3rovJZlUGpegJJyUApBTnVWK4pxSj 09:48:20 INFO - Expected file size: 16327493 09:48:20 INFO - Obtained file size: 16327493 09:48:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:48:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:48:26 INFO - Fetch https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:48:27 INFO - Http code: 200 09:48:27 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 09:48:27 INFO - x-amz-cf-id: Rt8qFxDJTg2WhkKiQFjk3zp0riB1K8MLCz2TND1UkPi7NEX_0OkhBQ== 09:48:27 INFO - x-amz-version-id: nZy2TRubHfItKTFzKY23wLmggGtvzYcP 09:48:27 INFO - Expected file size: 14409038 09:48:27 INFO - Obtained file size: 14409038 09:48:28 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')]} 09:48:28 INFO - trying https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:48:28 INFO - Downloading https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:48:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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 09:48:30 INFO - Downloaded 114362976 bytes. 09:48:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:48:30 INFO - mkdir: /builds/slave/test/properties 09:48:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:30 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:30 INFO - Contents: 09:48:30 INFO - build_url:https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:48:31 INFO - Running post-action listener: _resource_record_post_action 09:48:31 INFO - Running post-action listener: set_extra_try_arguments 09:48:31 INFO - [mozharness: 2016-11-14 17:48:31.692747Z] Finished download-and-extract step (success) 09:48:31 INFO - [mozharness: 2016-11-14 17:48:31.692895Z] Running populate-webroot step. 09:48:31 INFO - Running pre-action listener: _resource_record_pre_action 09:48:31 INFO - Running main action method: populate_webroot 09:48:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:31 INFO - '--tppagecycles', 09:48:31 INFO - '7'], 09:48:31 INFO - 'tests': ['tsvgm']}}, 09:48:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['damp', 'tps']}, 09:48:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'tests': ['damp', 'tps']}, 09:48:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['dromaeo_dom']}, 09:48:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['basic_compositor_video']}, 09:48:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:48:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore', 09:48:31 INFO - 'tabpaint']}, 09:48:31 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore', 09:48:31 INFO - 'tabpaint']}, 09:48:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore', 09:48:31 INFO - 'tabpaint']}, 09:48:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore', 09:48:31 INFO - 'tabpaint']}, 09:48:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore', 09:48:31 INFO - 'tabpaint']}, 09:48:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['a11yr', 09:48:31 INFO - 'ts_paint', 09:48:31 INFO - 'tpaint', 09:48:31 INFO - 'sessionrestore', 09:48:31 INFO - 'sessionrestore_no_auto_restore', 09:48:31 INFO - 'tabpaint']}, 09:48:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['tsvgx', 09:48:31 INFO - 'tsvgr_opacity', 09:48:31 INFO - 'tart', 09:48:31 INFO - 'tscrollx', 09:48:31 INFO - 'cart']}, 09:48:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:31 INFO - 'tsvgr_opacity', 09:48:31 INFO - 'tart', 09:48:31 INFO - 'tscrollx', 09:48:31 INFO - 'cart']}, 09:48:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--disable-e10s'], 09:48:31 INFO - 'tests': ['tp5o']}, 09:48:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'tests': ['tp5o']}, 09:48:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--disable-e10s', 09:48:31 INFO - '--xperf_path', 09:48:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:31 INFO - 'tests': ['tp5n']}, 09:48:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:31 INFO - 'talos_options': ['--xperf_path', 09:48:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:31 INFO - 'tests': ['tp5n']}}, 09:48:31 INFO - 'talos.zip': {'path': '', 09:48:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:31 INFO - Running post-action listener: _resource_record_post_action 09:48:31 INFO - [mozharness: 2016-11-14 17:48:31.712439Z] Finished populate-webroot step (success) 09:48:31 INFO - [mozharness: 2016-11-14 17:48:31.712578Z] Running create-virtualenv step. 09:48:31 INFO - Running pre-action listener: _resource_record_pre_action 09:48:31 INFO - Running main action method: create_virtualenv 09:48:31 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:31 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 09:48:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:31 INFO - Using real prefix '/tools/python27' 09:48:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:32 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:35 INFO - Installing pip.................done. 09:48:35 INFO - Return code: 0 09:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:48:35 INFO - Reading from file tmpfile_stdout 09:48:35 INFO - Output received: 09:48:35 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) 09:48:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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', 'pip>=1.5'] in /builds/slave/test/build 09:48: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 pip>=1.5 09:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:35 INFO - 'HOME': '/Users/cltbld', 09:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:35 INFO - 'LOGNAME': 'cltbld', 09:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:35 INFO - 'MOZ_NO_REMOTE': '1', 09:48:35 INFO - 'NO_EM_RESTART': '1', 09:48:35 INFO - 'PAGER': '/bin/cat', 09:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:35 INFO - 'PWD': '/builds/slave/test', 09:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:35 INFO - 'SHELL': '/bin/bash', 09:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:35 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:35 INFO - 'USER': 'cltbld', 09:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:35 INFO - 'XPC_FLAGS': '0x0', 09:48:35 INFO - 'XPC_SERVICE_NAME': '0', 09:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48: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 09:48:35 INFO - Cleaning up... 09:48:35 INFO - Return code: 0 09:48:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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 09:48: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 09:48:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:35 INFO - 'HOME': '/Users/cltbld', 09:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:35 INFO - 'LOGNAME': 'cltbld', 09:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:35 INFO - 'MOZ_NO_REMOTE': '1', 09:48:35 INFO - 'NO_EM_RESTART': '1', 09:48:35 INFO - 'PAGER': '/bin/cat', 09:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:35 INFO - 'PWD': '/builds/slave/test', 09:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:35 INFO - 'SHELL': '/bin/bash', 09:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:35 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:35 INFO - 'USER': 'cltbld', 09:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:35 INFO - 'XPC_FLAGS': '0x0', 09:48:35 INFO - 'XPC_SERVICE_NAME': '0', 09:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:36 INFO - Downloading/unpacking psutil>=3.1.1 09:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:39 INFO - Installing collected packages: psutil 09:48:39 INFO - Running setup.py install for psutil 09:48:39 INFO - building 'psutil._psutil_osx' extension 09:48: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 09:48:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:48:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:48:40 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 09:48:40 INFO - building 'psutil._psutil_posix' extension 09:48:40 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 09:48:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:48:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:48:40 INFO - ^ 09:48:40 INFO - 1 warning generated. 09:48:40 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 09:48:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:40 INFO - Successfully installed psutil 09:48:40 INFO - Cleaning up... 09:48:40 INFO - Return code: 0 09:48:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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 09:48: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 09:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:40 INFO - 'HOME': '/Users/cltbld', 09:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:40 INFO - 'LOGNAME': 'cltbld', 09:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:40 INFO - 'MOZ_NO_REMOTE': '1', 09:48:40 INFO - 'NO_EM_RESTART': '1', 09:48:40 INFO - 'PAGER': '/bin/cat', 09:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:40 INFO - 'PWD': '/builds/slave/test', 09:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:40 INFO - 'SHELL': '/bin/bash', 09:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:40 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:40 INFO - 'USER': 'cltbld', 09:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:40 INFO - 'XPC_FLAGS': '0x0', 09:48:40 INFO - 'XPC_SERVICE_NAME': '0', 09:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48:41 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 09:48:41 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 09:48:41 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 09:48:41 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 09:48:41 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 09:48:41 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 09:48:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:43 INFO - Installing collected packages: mozsystemmonitor 09:48:43 INFO - Running setup.py install for mozsystemmonitor 09:48:43 INFO - Successfully installed mozsystemmonitor 09:48:43 INFO - Cleaning up... 09:48:43 INFO - Return code: 0 09:48:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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 09:48: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 09:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:43 INFO - 'HOME': '/Users/cltbld', 09:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:43 INFO - 'LOGNAME': 'cltbld', 09:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:43 INFO - 'MOZ_NO_REMOTE': '1', 09:48:43 INFO - 'NO_EM_RESTART': '1', 09:48:43 INFO - 'PAGER': '/bin/cat', 09:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:43 INFO - 'PWD': '/builds/slave/test', 09:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:43 INFO - 'SHELL': '/bin/bash', 09:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:43 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:43 INFO - 'USER': 'cltbld', 09:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:43 INFO - 'XPC_FLAGS': '0x0', 09:48:43 INFO - 'XPC_SERVICE_NAME': '0', 09:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:44 INFO - Downloading/unpacking jsonschema==2.5.1 09:48:44 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 09:48:44 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 09:48:44 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 09:48:44 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 09:48:44 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 09:48:44 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 09:48:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:47 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:47 INFO - warning: no files found matching '*.txt' 09:48:47 INFO - no previously-included directories found matching 'build' 09:48:47 INFO - no previously-included directories found matching 'dist' 09:48:47 INFO - no previously-included directories found matching '.git*' 09:48:47 INFO - Installing collected packages: jsonschema, functools32 09:48:47 INFO - Running setup.py install for functools32 09:48:47 INFO - warning: no files found matching '*.txt' 09:48:47 INFO - no previously-included directories found matching 'build' 09:48:47 INFO - no previously-included directories found matching 'dist' 09:48:47 INFO - no previously-included directories found matching '.git*' 09:48:47 INFO - Successfully installed jsonschema functools32 09:48:47 INFO - Cleaning up... 09:48:47 INFO - Return code: 0 09:48:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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 09:48: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 09:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:47 INFO - 'HOME': '/Users/cltbld', 09:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:47 INFO - 'LOGNAME': 'cltbld', 09:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:47 INFO - 'MOZ_NO_REMOTE': '1', 09:48:47 INFO - 'NO_EM_RESTART': '1', 09:48:47 INFO - 'PAGER': '/bin/cat', 09:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:47 INFO - 'PWD': '/builds/slave/test', 09:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:47 INFO - 'SHELL': '/bin/bash', 09:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:47 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:47 INFO - 'USER': 'cltbld', 09:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:47 INFO - 'XPC_FLAGS': '0x0', 09:48:47 INFO - 'XPC_SERVICE_NAME': '0', 09:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:47 INFO - Cleaning up... 09:48:47 INFO - Return code: 0 09:48:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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 09:48: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 09:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:47 INFO - 'HOME': '/Users/cltbld', 09:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:47 INFO - 'LOGNAME': 'cltbld', 09:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:47 INFO - 'MOZ_NO_REMOTE': '1', 09:48:47 INFO - 'NO_EM_RESTART': '1', 09:48:47 INFO - 'PAGER': '/bin/cat', 09:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:47 INFO - 'PWD': '/builds/slave/test', 09:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:47 INFO - 'SHELL': '/bin/bash', 09:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:47 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:47 INFO - 'USER': 'cltbld', 09:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:47 INFO - 'XPC_FLAGS': '0x0', 09:48:47 INFO - 'XPC_SERVICE_NAME': '0', 09:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:48 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:50 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:48:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:51 INFO - Downloading docopt-0.6.1.tar.gz 09:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:52 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:52 INFO - Running setup.py install for blobuploader 09:48:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:48:52 INFO - Running setup.py install for requests 09:48:52 INFO - Running setup.py install for docopt 09:48:52 INFO - Successfully installed blobuploader requests docopt 09:48:52 INFO - Cleaning up... 09:48:52 INFO - Return code: 0 09:48:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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 09:48: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 09:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:52 INFO - 'HOME': '/Users/cltbld', 09:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:52 INFO - 'LOGNAME': 'cltbld', 09:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:52 INFO - 'MOZ_NO_REMOTE': '1', 09:48:52 INFO - 'NO_EM_RESTART': '1', 09:48:52 INFO - 'PAGER': '/bin/cat', 09:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:52 INFO - 'PWD': '/builds/slave/test', 09:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:52 INFO - 'SHELL': '/bin/bash', 09:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:52 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:52 INFO - 'USER': 'cltbld', 09:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:52 INFO - 'XPC_FLAGS': '0x0', 09:48:52 INFO - 'XPC_SERVICE_NAME': '0', 09:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-u_3WcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-RQUqBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-QgMkzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-KUBnTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-nNsceh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_lUdia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-GltESC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-diNYlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:53 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-0A1i5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-igWGhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-YkfP_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-AYXmXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-pOrqc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-xeBe6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-AUq8vG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-XIOy4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:54 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-m2FGWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:54 INFO - Running setup.py install for manifestparser 09:48:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:48:55 INFO - Running setup.py install for mozcrash 09:48:55 INFO - Running setup.py install for mozdebug 09:48:55 INFO - Running setup.py install for mozdevice 09:48:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:48:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:48:55 INFO - Running setup.py install for mozfile 09:48:55 INFO - Running setup.py install for mozhttpd 09:48:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:48:55 INFO - Running setup.py install for mozinfo 09:48:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:48:55 INFO - Running setup.py install for mozInstall 09:48:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Running setup.py install for mozleak 09:48:56 INFO - Running setup.py install for mozlog 09:48:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Running setup.py install for moznetwork 09:48:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Running setup.py install for mozprocess 09:48:56 INFO - Running setup.py install for mozprofile 09:48:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:48:56 INFO - Running setup.py install for mozrunner 09:48:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:48:57 INFO - Running setup.py install for mozscreenshot 09:48:57 INFO - Running setup.py install for moztest 09:48:57 INFO - Running setup.py install for mozversion 09:48:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:48:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:57 INFO - Cleaning up... 09:48:57 INFO - Return code: 0 09:48:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48: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')]} 09:48:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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')]} 09:48:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:48: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 09:48: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 09:48:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:48:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:57 INFO - 'HOME': '/Users/cltbld', 09:48:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:57 INFO - 'LOGNAME': 'cltbld', 09:48:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:57 INFO - 'MOZ_NO_REMOTE': '1', 09:48:57 INFO - 'NO_EM_RESTART': '1', 09:48:57 INFO - 'PAGER': '/bin/cat', 09:48:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:57 INFO - 'PWD': '/builds/slave/test', 09:48:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:57 INFO - 'SHELL': '/bin/bash', 09:48:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:48:57 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:48:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:57 INFO - 'USER': 'cltbld', 09:48:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:57 INFO - 'XPC_FLAGS': '0x0', 09:48:57 INFO - 'XPC_SERVICE_NAME': '0', 09:48:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:57 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ydgB7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48: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)) 09:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:57 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-XzUjPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48: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)) 09:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:57 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-8ilRnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:58 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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-q6rBiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:58 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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-puTu1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:58 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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ySWsUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48: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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-kO3tc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48: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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Oihg1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48: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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-wO1jRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48: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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-QxbzrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48: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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-jyB4bG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48: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)) 09:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:58 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-DVizOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:59 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)) 09:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:59 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Cy0y0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:59 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)) 09:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:59 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-JK0zYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:59 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)) 09:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:59 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_GtAkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48: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)) 09:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:59 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-t_I6Br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48: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)) 09:48:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:59 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-qtxmoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48: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)) 09:48: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)) 09:48: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)) 09:48: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)) 09:48: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)) 09:48: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)) 09:48:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:49:02 INFO - Downloading blessings-1.6.tar.gz 09:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:02 INFO - Installing collected packages: blessings 09:49:02 INFO - Running setup.py install for blessings 09:49:02 INFO - Successfully installed blessings 09:49:02 INFO - Cleaning up... 09:49:02 INFO - Return code: 0 09:49:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:02 INFO - Reading from file tmpfile_stdout 09:49:02 INFO - Current package versions: 09:49:02 INFO - blessings == 1.6 09:49:02 INFO - blobuploader == 1.2.4 09:49:02 INFO - docopt == 0.6.1 09:49:02 INFO - functools32 == 3.2.3-2 09:49:02 INFO - jsonschema == 2.5.1 09:49:02 INFO - manifestparser == 1.1 09:49:02 INFO - mozInstall == 1.12 09:49:02 INFO - mozcrash == 1.0 09:49:02 INFO - mozdebug == 0.1 09:49:02 INFO - mozdevice == 0.48 09:49:02 INFO - mozfile == 1.2 09:49:02 INFO - mozhttpd == 0.7 09:49:02 INFO - mozinfo == 0.9 09:49:02 INFO - mozleak == 0.1 09:49:02 INFO - mozlog == 3.4 09:49:02 INFO - moznetwork == 0.27 09:49:02 INFO - mozprocess == 0.23 09:49:02 INFO - mozprofile == 0.28 09:49:02 INFO - mozrunner == 6.13 09:49:02 INFO - mozscreenshot == 0.1 09:49:02 INFO - mozsystemmonitor == 0.3 09:49:02 INFO - moztest == 0.7 09:49:02 INFO - mozversion == 1.4 09:49:02 INFO - psutil == 3.1.1 09:49:02 INFO - requests == 1.2.3 09:49:02 INFO - wsgiref == 0.1.2 09:49:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49: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')]} 09:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49: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')]} 09:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49: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 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:49: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 09:49: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 09:49:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:49:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:03 INFO - 'HOME': '/Users/cltbld', 09:49:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:03 INFO - 'LOGNAME': 'cltbld', 09:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:03 INFO - 'MOZ_NO_REMOTE': '1', 09:49:03 INFO - 'NO_EM_RESTART': '1', 09:49:03 INFO - 'PAGER': '/bin/cat', 09:49:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:03 INFO - 'PWD': '/builds/slave/test', 09:49:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:03 INFO - 'SHELL': '/bin/bash', 09:49:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:49:03 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:49:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:03 INFO - 'USER': 'cltbld', 09:49:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:03 INFO - 'XPC_FLAGS': '0x0', 09:49:03 INFO - 'XPC_SERVICE_NAME': '0', 09:49:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49: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)) 09:49:03 INFO - Cleaning up... 09:49:03 INFO - Return code: 0 09:49:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49: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')]} 09:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49: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')]} 09:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102150490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1020c6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2479a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, '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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 09:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:49:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:49:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:04 INFO - 'HOME': '/Users/cltbld', 09:49:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:04 INFO - 'LOGNAME': 'cltbld', 09:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:04 INFO - 'MOZ_NO_REMOTE': '1', 09:49:04 INFO - 'NO_EM_RESTART': '1', 09:49:04 INFO - 'PAGER': '/bin/cat', 09:49:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:04 INFO - 'PWD': '/builds/slave/test', 09:49:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:04 INFO - 'SHELL': '/bin/bash', 09:49:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:49:04 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:49:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:04 INFO - 'USER': 'cltbld', 09:49:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:04 INFO - 'XPC_FLAGS': '0x0', 09:49:04 INFO - 'XPC_SERVICE_NAME': '0', 09:49:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:49:04 INFO - Cleaning up... 09:49:04 INFO - Return code: 0 09:49:04 INFO - Running post-action listener: _resource_record_post_action 09:49:04 INFO - Running post-action listener: _start_resource_monitoring 09:49:04 INFO - Starting resource monitoring. 09:49:04 INFO - [mozharness: 2016-11-14 17:49:04.451398Z] Finished create-virtualenv step (success) 09:49:04 INFO - [mozharness: 2016-11-14 17:49:04.452204Z] Running install step. 09:49:04 INFO - Running pre-action listener: _resource_record_pre_action 09:49:04 INFO - Running main action method: install 09:49:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:04 INFO - Reading from file tmpfile_stdout 09:49:04 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:04 INFO - Reading from file tmpfile_stdout 09:49:04 INFO - Output received: 09:49:04 INFO - Usage: mozinstall [options] installer 09:49:04 INFO - Options: 09:49:04 INFO - -h, --help show this help message and exit 09:49:04 INFO - -d DEST, --destination=DEST 09:49:04 INFO - Directory to install application into. [default: 09:49:04 INFO - "/builds/slave/test"] 09:49:04 INFO - --app=APP Application being installed. [default: firefox] 09:49:04 INFO - mkdir: /builds/slave/test/build/application 09:49: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'] 09:49: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 09:49:36 INFO - Reading from file tmpfile_stdout 09:49:36 INFO - Output received: 09:49:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:49:36 INFO - Running post-action listener: _resource_record_post_action 09:49:36 INFO - [mozharness: 2016-11-14 17:49:36.181476Z] Finished install step (success) 09:49:36 INFO - [mozharness: 2016-11-14 17:49:36.181643Z] Running run-tests step. 09:49:36 INFO - Running pre-action listener: _resource_record_pre_action 09:49:36 INFO - Running main action method: run_tests 09:49:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:36 INFO - 2016-11-14 09:49:36.196 screenresolution[1637:7457] starting screenresolution argv=screenresolution get 09:49:36 INFO - 2016-11-14 09:49:36.198 screenresolution[1637:7457] Display 0: 1600x1200x32@60 09:49:36 INFO - 2016-11-14 09:49:36.204 screenresolution[1638:7460] starting screenresolution argv=screenresolution list 09:49:36 INFO - Available Modes on Display 0 09:49:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:36 INFO - Intel Iris: 09:49:36 INFO - Chipset Model: Intel Iris 09:49:36 INFO - Type: GPU 09:49:36 INFO - Bus: Built-In 09:49:36 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:36 INFO - Vendor: Intel (0x8086) 09:49:36 INFO - Device ID: 0x0a2e 09:49:36 INFO - Revision ID: 0x0009 09:49:36 INFO - Displays: 09:49:36 INFO - Display: 09:49:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:36 INFO - Display Serial Number: 1600x1200 60 09:49:36 INFO - Main Display: Yes 09:49:36 INFO - Mirror: Off 09:49:36 INFO - Online: Yes 09:49:36 INFO - Rotation: Supported 09:49:36 INFO - Return code: 0 09:49:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:36 INFO - Python 2.7.3 09:49:36 INFO - Return code: 0 09:49:36 INFO - grabbing minidump binary from tooltool 09:49: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')]} 09:49: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 0x101f64d30>, '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 0x101dcadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101f5cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49: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 09:49: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 09:49: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 09:49:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:36 INFO - Return code: 0 09:49:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:49:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:49:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49: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/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 09:49: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/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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 09:49:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QhvtmhAFNa/Render', 09:49:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:36 INFO - 'HOME': '/Users/cltbld', 09:49:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:36 INFO - 'LOGNAME': 'cltbld', 09:49:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:36 INFO - 'MOZ_NO_REMOTE': '1', 09:49:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:36 INFO - 'NO_EM_RESTART': '1', 09:49:36 INFO - 'PAGER': '/bin/cat', 09:49:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:36 INFO - 'PWD': '/builds/slave/test', 09:49:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:36 INFO - 'SHELL': '/bin/bash', 09:49:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7a2W4mDRnB/Listeners', 09:49:36 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 09:49:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:36 INFO - 'USER': 'cltbld', 09:49:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:36 INFO - 'XPC_FLAGS': '0x0', 09:49:36 INFO - 'XPC_SERVICE_NAME': '0', 09:49:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49: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/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 09:49:36 INFO - mozversion application_buildid: 20161114043447 09:49:36 INFO - mozversion application_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 09:49:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:49:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:36 INFO - mozversion application_name: Firefox 09:49:36 INFO - mozversion application_remotingname: firefox-dev 09:49:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:36 INFO - mozversion application_vendor: Mozilla 09:49:36 INFO - mozversion application_version: 52.0a2 09:49:36 INFO - mozversion platform_buildid: 20161114043447 09:49:36 INFO - mozversion platform_changeset: 7e29173b1641f54d08c60a64cd9d1b5fbc3bba2f 09:49:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:36 INFO - mozversion platform_version: 52.0a2 09:49:36 INFO - using testdate: 1479145776 09:49:36 INFO - actual date: 1479145776 09:49:36 INFO - starting webserver on 'localhost:49271' 09:49:36 INFO - SUITE-START | Running 2 tests 09:49:36 INFO - TEST-START | dromaeo_css 09:49:36 INFO - operating with platform_type : mac_ 09:49:36 INFO - Initialising browser for dromaeo_css test... 09:49:36 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpvaCKoz/profile http://localhost:49271/getInfo.html) 09:49:38 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 09:49:39 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 09:49:39 INFO - PROCESS | 1648 | colorDepth:24 09:49:39 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 09:49:39 INFO - PROCESS | 1648 | __metrics 09:49:39 INFO - PROCESS | 1648 | 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" 09:49:39 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:39 INFO - TEST-INFO | 1648: exit 0 09:49:39 INFO - Browser initialized. 09:49:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:39 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpvaCKoz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:52 INFO - PROCESS | 1650 | RSS: Main: 217292800 09:49:52 INFO - PROCESS | 1650 | 09:50:42 INFO - PROCESS | 1650 | 1479145842974 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] 09:52:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 09:52:59 INFO - PROCESS | 1650 | RSS: Main: 424218624 09:52:59 INFO - PROCESS | 1650 | 09:56:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 09:56:02 INFO - PROCESS | 1650 | RSS: Main: 352403456 09:56:02 INFO - PROCESS | 1650 | 09:59:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 09:59:05 INFO - PROCESS | 1650 | RSS: Main: 322105344 09:59:05 INFO - PROCESS | 1650 | 10:02:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 10:02:08 INFO - PROCESS | 1650 | RSS: Main: 319352832 10:02:08 INFO - PROCESS | 1650 | 10:05:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 10:05:12 INFO - PROCESS | 1650 | RSS: Main: 315723776 10:05:12 INFO - PROCESS | 1650 | 10:08:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 10:08:16 INFO - PROCESS | 1650 | RSS: Main: 408375296 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | __start_tp_report 10:08:16 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 10:08:16 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 10:08:16 INFO - PROCESS | 1650 | |i|pagename|runs| 10:08:16 INFO - PROCESS | 1650 | |0;dojo.html;3140.450421176294;3261.8695252189914;3264.7388208943294;3281.0976981330145;3314.171457135716;5300.761465734042;5676.772929082842;5695.145728140781;5715.971420142904;5727.140928860673;5628.690422026787;5694.288213973254;5711.485966263035;5732.885342293152;5737.340205876326;5147.6654017488745;5186.222066689989;5188.533032027117;5209.687418754868;5257.868553286179;5111.361079865017;5153.922691159617;5157.871053223662;5232.424433312332;5303.045451818671;4350.956490435086;5224;5229.163333866582;5238.214267859814;5240.34495688039;5233.973830130844;5239.056969745444;5242;5245.370555533323;5262.684238945675;5099.388073431175;5201.453847346012;5203.4796520348045;5218.425973142951;5259.316288882459;5088.313077734496;5534.889302213933;5767.019606666835;5784.855378615526;5786.450287222707;6108.858142493672;6167.506599472076;6167.845803854894;6183.412575805291;6190.659513726743;6208.441240288395;6274.121622972787;6284.12022316876;6290.119383286344;6296.87406251877;5763.717398973472;5794.362620111784;5847.532197424196;5865.120231965197;5877.764889404419;6230.470410015108;6306.87386252277;6312.43188113072;6313.368663133741;6330.145430366933;4860.756962151878;6068.908966365508;6093.634381937098;6133.591519773218;6134.528431832502;6160.866517872734;6209.472194863397;6257.0614407838275;6264.310925798159;6264.624122552661;6186.566940314135;6244.687765611701;6268.216472940882;6277.52918531112;6277.654728989858;5427.945720542823;6118.284172697687;6124;6157.6613286269685;6180.6909654517995;4973.502649734997;4981.32752078472;4983.651144419929;4985.376827896513;5003.099442100457;3787.8484860605818;4462.152191083684;4464.15181115587;4469.776511174493;4476.149531588988;5629.014922388647;5634.915476267859;5673.74468148926;5761.596688231867;5794.565407594448;6290.937090629127;6348.206474190726;6358.364163583605;6367.458766004941;6370.872582548323;5799.956007918531;5822.7670893163795;5824.95150872847;5835.572917187057;5855.560832937546;5327.893442131136;5382.1119515280325;5442.91835622466;5445.346558412936;5455;6203.286622038523;6218.9378106218355;6248.625082495065;6289.779857704958;6297.874042519215;6331.5884467511305;6352.491800656029;6359.141515895295;6362.981922892315;6374.458171055327;6194.969025154845;6300.71646775887;6322.209723784527;6324.557280990287;6328.177336946167;6046.062860256668;6109.222480666452;6116.418940200674;6123.221615589998;6177.320494745484;5258.868528286824;6230.844228894314;6306;6345.238571371465;6347.111404403295;5944.1975333331175;6148.323684394623;6181.567290289637;6196.938030619816;6205.286392064971;6174.598651087665;6198;6209.782657607143;6216.844578885564;6218.067289906369;4969.577585905238;5844.12338149281;5890.882182356415;5939.138824870283;5977.491913187428;5105.591552675852;5931.4068593142065;6005.189299444519;6145.617236121837;6161.753529858755;5892.528597712087;5930.495907847709;5941.67320797343;6003.6397816131175;6037.969810150921 10:08:16 INFO - PROCESS | 1650 | |1;ext.html;469.05485446824656;469.713474780384;482.97127119236063;504.0951492071731;559.734126290012;33601.66398336016;38372;38486.61513384865;38694.8391548254;38751.8374448767;39217.60782392175;39685.206295874064;39740.006499837444;39803.80098099505;39883.8034858954;9019.323550733689;9191.034941331165;9209.079092090804;9220.492872891988;9240.214581760525;7892;9166.220871225954;9169.403988740743;9174.220191283748;9175.908240917606;9139.543022848864;9197.586108625128;9197.862032069525;9236.815263694722;9247.722568322928;9123.270138388945;9178.357514973955;9188.63245470187;9191.724248272563;9195.954020229923;9061.13919177677;9144.268458523302;9165.725028249164;9170.082991700776;9172.44965302084;31455.842720786248;37859;38062.80968595139;38258;38259.42610860839;3338.115399419156;3428.879989200366;3527.7177825773874;3688.926221475582;3785.3943369060817;23519.529609407888;28282.010129645365;29084.836606535715;29423.852880735674;29455.116346509425;3117.547955546433;3122.73456756176;3149.1497295730023;3159.7472202223767;3174.666660000713;469987;481394;484421;590398.9999999957;592119;58033.709831451415;58541.41458585445;58663.41336586665;58680.7065964676;58848;32851.3429731404;33089.834550827094;33349.83325083359;61472.69263653653;61790.67465277387;15734.898557101122;15874;15893;15909.840901591067;15912.92043539775;12101.273923564615;12182.269063856196;12195.207311524722;12204.877951220375;12209.450574724158;17944.820551794313;18032.459026229233;18037.368692095977;18057.638847223243;18154.727679084823;7330.486865919441;7409.703611855465;7413.406927445792;7483.438742094256;7493.288137626917;855.4653341661461;918.3525614441802;949.886013678363;991.3506653142204;1079.379356869803;27278.727212728016;27919.302017449725;28666.856665716954;28761.56857647137;28835;1033.7467320506523;1036.1555332404066;1060.4538662588773;1079.578964203961;1088.1675518228562;14623.707525849424;14672.633184170481;14912.70174596502;15077.246137693071;15090;664218.4312627441;665714;666797.6660116669;667495;670083;52722;53320.73339633277;53328.933421331356;53394;53445.7327713359;1213.2962881528692;1253.6552448076852;1325.8796317112026;1342.2550484481012;1364.6656569140623;28446.288842779097;28970.27574310659;29624.555631665113;29685.85157074201;30467.695323047374;45282.32076518921;45844.54155458403;45932.311015335465;46071.76964115158;46071.848203795176;460532.69733652455;460555;460595;460973;463794;21506.569868602226;24455.75544244535;25124.24627261185;25192.874035629706;25238.116665916605;43757;43767.56232437635;43942.56057439385;44328.556714432445;44473.110537788416;36340;36840;36843.07892302714;36866;37938.810305949395;36267.27465450623;36539.903802884895;37398.8130059348;37406;37777.866664000125;35419.468707969914;35726.464103039;35827.641723582434;36205.637943620226;36462.817685912465;27262.863685682238;31244.375112498077;33081.33837323288;33158.66841331556;33214.5017824728;24303.027878884648;24493.142740004016;24690.25929222126;24741.752582474666;24945.875270624256 10:08:16 INFO - PROCESS | 1650 | |2;jquery.html;363.96906262967644;375.53996354465784;381.89116101910963;393.5316972802363;403.90710136668565;17264.568385790346;18800.90599547004;19544.609107817854;19675.409737707872;19777.703334449958;19600.70598941017;19743;19859.01409859017;20022.799772002276;20161.899190504028;4780.880477988061;5642.830715078554;5774.249347584829;5940.198073260098;5945.137954996523;4041.4139949707273;5691.886162276752;5816.854578635525;5829.766809327643;5830.125481177814;4219.725717828332;4284.914301713964;6048.546359023069;6362.0456931460185;6375.968120159416;6159.938400616026;6352.523560732917;6427.967860160693;6429.903551446732;6444.258910225314;5614.242077319551;5619.775208991636;5619.803306884281;5621.887562248732;5622.550195984311;19836.404907852644;23685.526289474285;23951.520969580684;23983.640245396335;24333.756662433327;2207.701960235364;2272.761360057203;2844.6586409630977;3043.421749867518;3125.3280544683003;14670.559883203521;14789.778153327708;14790.038647487987;15175.848241517553;15319.617009574738;1554.8289688134296;1603.2624992503463;1805.1696219738935;1848.0851978270841;1885.2930151193302;102631.97368026373;102857.48571257171;104837;105215;105365;10572.947135264274;10589.947050264853;10639.946800265949;10706.357618542912;10919.781604368027;13804.792928106086;13814.93092534551;13835.446582136803;13843.86156138446;13885.4445822168;7357.822748360237;8166.7958301042945;8457.619407126695;8459.450135741157;8551.033262349694;7242.384397326181;8047.8732977383215;8344.707935222359;8524.275436587835;8547.35894807892;5385.461453854583;6626.602403855784;6671;6671.39957403836;6678.460415979539;1670.4153546258713;1795.2729144696418;1835.8898466092078;1842.2723024405284;1844.6994868617592;808.9797755056171;853.5476197615274;861.8491763941286;923.4182465046978;945.262695097811;19781.307654232034;20925.37223883286;20980.475488112614;21013;21122.577548448946;939.5584075484511;952.5618215620892;955.2310390136007;968.7529679931572;1025.415513157508;9530.666426675032;10927.726806829893;11408.030317423112;11472.713182170513;11539.769204616028;556304;581084;581622;591711;596219;64483.67758161273;88412;88821.22357552813;90711.63932541017;90952.54523727339;986.3539381704851;993.4883534979491;1015.8780946286493;1016.9033941775667;1018.7351288665001;18483.815161848208;18894.433167005012;18947.33684321042;19003.334883279018;19021.71467427962;47158.528414715416;47169;47243.52756472391;47495.81260468516;48347;62338;65926.67036664786;69916;74387.62806185934;81450.18549814663;7801.336886364723;7990;8066.596670166586;8103.351731861555;8212.712555060543;10423.895761042293;12416.317102559446;12615.36923153857;13088.607341779762;13118.278494682883;9803.921568627587;10681.57273709043;10746;10986.505607247853;11200.495977681187;10445.582176712845;10960.28758130719;10978.12175026012;11179.385133817717;11486.597969071043;10597.152227821602;11081.27971681838;11147.721306967067;11412.717456508566;11571.768564628828;8062.798430039296;8152.429329947084;8158;8270.090290067967;8290.668373265002;5491.450854914477;5699;7788.727394541163;11121.610743623934;11221.214514983873 10:08:16 INFO - PROCESS | 1650 | |3;mootools.html;1257.9433925473352;1273.9617811465657;1459.0224549551804;1460.4596299369234;1720.7849018872632;7491.8876216856725;7889.8816517752275;8190.099089100202;8434.53610051447;8468.153184681529;8355.080941096474;8435.114312997139;8440.029396619391;8443.451175673577;8465.619047142878;2308.803751681109;2341.695579574653;2344.6951896253463;2345.8240631952585;2348.471593891378;2323.53529294141;2372.2408829174583;2373.3947843299934;2376.572217000939;2378.940526486843;1990.691442826357;2317.455397981473;2327.359976006603;2348.5791096386697;2354.21133920137;2282.5116555897102;2299.494111295512;2315.166540045599;2315.918942837001;2319.3621754017613;2116.6613341865227;2194.846360754748;2351.8000969917407;2358.905643774247;2359.1153317505937;8282.088970213272;8351.498910065415;8391.370647201484;8426.283765879907;8432.198941100585;5015.0722116408215;5050.621203409759;5208.322918020671;5224.242484839715;5231.973840130775;3210.871565137392;3325.384803811303;3387.6442973487674;3407.6421975692692;3409.590849098099;1054.9683509494728;1072.8917676765193;1095.7863216672752;1097.4238524774478;1102.079763397564;10859.511321990529;10920.235583509157;11090.112790976702;11090.168237382148;11110.888891111146;4267.317229243306;4354.6951713380395;4464.441944756905;4469.284914413678;4473.619742321939;8527.019392769911;8642.09258027898;8678.86981695275;8688.95655521731;8702.608382622797;5015.3730783652045;5071.39143302806;5082.186850103965;5092.8472145835685;5094.541491265803;4065.593440655911;4295;4443.622292105143;4449.599536041772;4778.761061946958;3630.8184590770356;3646.781193128421;3733.3466643337306;3746.4754934309217;3749.662530372225;1142.5943789954613;1143.937083460401;1148.833419154218;1156.0982433701727;1160.8723040465543;854.2567965869733;878.7759121424124;883.1124719656832;891.9018907920124;904.7602385367883;4706.247000479975;5011.473795251446;5047.712833227503;5456.290682211287;5479.123340265538;419.7943007926155;428.5842732549422;431.7905815679393;438.2352794373838;458.1386992454165;3736.831842567037;3743.5694895086867;3745.6816170625257;3802.049487628093;3896.844126234919;9926.404415735078;10284;10364.896351036545;10367.792644147075;10397.896021039844;9485.430874147572;9669.90330096704;10198.388096714221;10338.793224135476;10339.0694837465;7738.574378409242;7750.031246094239;7832.960835195788;7851.803704907309;8028.11690714021;5537.1140617501005;5613.186088017296;5654.6889921054735;5770.884582308414;5927;7771.378289736695;9278.211352034883;9279.814403712024;9370.953145234504;9382.67160649374;7553;9540.904590954002;9589.089036541518;9637.614495420105;9672.806543869225;7151.213366529573;7316.707331706672;7319.59742214162;7325.377342925911;7326.780196593898;7627.961860190663;7909.7231596893835;7994.240547148244;8073.313768329758;8077.151899050515;7047.47143964215;7097.893531596927;7134.643267836484;7218.205997340184;7310.524815887163;6998.440124789903;7070.469714771516;7090.255523170199;7114.466415018997;7132.108486439195;6632.635205063768;6693.497987650849;6704.497162712719;6793.762218322335;6813.727450901908;4616.976915115403;4633.166030114612;4673;4722.932906424119;4822.300766388784;4811.013742182914;4833.2025215840395;4854.271859221145;4942;4979.576735977483 10:08:16 INFO - PROCESS | 1650 | |4;prototype.html;302.9878804847806;306.40710225713246;308.9258577941294;316.7640108119451;318.47928636679023;5177.430482646911;5211.166213405856;5235.795766973599;5239.973800130994;5240.44975277596;4863.537963893425;5084.262781896623;5089.236614507826;5136.126858434066;5159.922601160985;3365.276465559898;3767.1900541383598;3879.72841901067;3888.6889048876164;3957.3866050762035;3043.9543406848916;3655.104499397638;3944.9211015779665;3949.269385163754;3970.265500882346;3901.239258344624;3907.863224787133;3927.7643341399466;3950.3481925482406;3972.9602703973023;3378.2736711606876;3440.0539851540775;3646.751534462348;3754.9436758448646;3869.8839034829;3049.4815881300233;3110.9844450777828;3150.9444336147417;3312.950305745416;3499.160201551609;4211.831526738927;5051.570616497602;5089.236614507853;5092.541671249568;5111.437741848394;3829.2341531693774;4188.5811418858175;4210.157968406331;4307.633851122659;4598.310253461974;5278.47215278473;5402.540784033362;5435.673859568399;5444.646098003617;5449.754761035763;3850.4994350734505;3856.0745421099014;3869.7423337415453;3883.262180185756;3891.8248678809523;5022.425108129973;5678.687672178028;5687.971560142173;5688.232088668018;5726.907469966213;3275.901722948268;3421.9486707699402;3909.58949310324;3920.7059470539825;3931.783751893616;3991.3214753491975;4008.779517126586;4008.8797336079383;4011.6389524942897;4019.3569028955226;3847.8845634631007;4562.064776720765;4569.702969306984;4601.815927362935;4611.953880461152;4586.747728874877;5253.737313134328;5393;5402.810901618424;5451;1181.1141643767176;1210.680358409338;1237.8452693413324;1254.3728135932033;1273.6815796050987;789.2935822438885;796.3231253434534;845.6363763581719;878.9560521973875;897.708244820436;4295.926018495376;4797.976010119927;4991.8535029937475;5092.363454568179;5156;4482.484514280899;5598.356189038232;5617.466340697627;5622.381538030813;5647.661140331593;4314.7626880521875;4335;4911.656184067152;5071.974640126849;5120.436751957282;2738.3975525384385;2746.231055304478;2775.34779326858;2778.7776977841722;2805.495010898017;4839.685420447659;5085.974570127126;5547;5552.666839989613;5562.777488900399;4570.542945705402;5147.819826306061;5431.8098866539485;5447.073997420448;5464.9453505465235;5332.920315958295;5461.890762184814;5528.9723551381985;5548;5579.553635709133;4570.565796249351;4636.86089417318;5249.422563517934;5273.551748101454;5386.461353864739;5154.716490593053;5235.65968212065;5288.2860813791185;5543.25166102571;5618.6628802272;5884.645751921364;5889.882202355843;5904.557158213065;5931.220633809753;5942.603488180188;3814.5422549294262;3821.293060783764;3840.1743625120735;3857.267119247334;3864.9806750966063;4794.089123066746;5371.785128594813;5675.517581005661;5697.116946873241;5736.71316434185;5521.5306698929435;5535.916961245504;5567.387587365306;5569.526590239875;5571.749271282721;4848.587870031087;5293.708846013506;5533.6403133796175;5545.694986775766;5566.68982372311;5310.9734451327495;5375.838724838261;5381.408045115112;5381.448244158836;5405.567524605904;4159.937600935928;5225.047847965887;5258.737063146751;5357.705326207096;5404.21682833349;4202.965153452719;4509.458864936229;4863.641134050054;5111.718855462986;5410.161424979135 10:08:16 INFO - PROCESS | 1650 | |5;yui.html;681.7170874087254;690.5787469643517;706.8303607134286;741.2217171969432;762.6034462079718;14051.648708782286;14585.645301580644;14616.123032618023;14642;14658.267086645665;12916.16044957077;12977.805332920014;13005.934970325136;13069.67325816855;13097.345132743372;634.3814780588935;658.0294066252287;662.5792621685216;786.7836345005112;814.3851392198882;803.9035315762087;814.3729328417115;816.147126253064;818.8485130250923;821.7165078048064;860.5439117267858;861.9051904290523;862.8835107260534;862.9611667474978;863.7495126413332;662.4104546953215;855.0380821575727;857.3698331726176;857.4598003257908;858.2618947704968;513.7508308470389;618.5144661440793;638.773235501395;642.5245318464349;647.1360733420888;13837.792433113511;13882.583522494342;13882.722345553133;13899.722005559832;13917;12969.221846689228;13075.934620326838;13128.934355328258;13297.667558311023;13298.933505332509;11978.221415607957;12081.758364832742;12103.93948030263;12358.196717213352;12644.367781610883;4382.452193475816;4409.074094440171;4449.5327990560845;4513.074819661962;4550.112728018038;73375;89940.55029724874;90823;92689;93035.53482232614;10416.843747343795;10524.210684198715;10644.148468122532;10780.568777248822;10857.728556786144;20038.118684111167;20280;20293.28973485921;20516.897415512827;20720.06759695817;7518.887216691754;7537.2085930977555;7558.811029724301;7609.505382150142;7743.7676869694;3898.3567711327314;4204.159168166379;4239.851605193865;4489.371487991683;4515.412996310524;3194.776365654382;3283.8193899335765;3284.080457471912;3287.178205448638;3287.408266512051;603.5081408652022;605.8606520500222;613.6440864298696;617.5121653893464;624.7188765055743;535.7455208775879;581.7614777941109;593.4362355762044;607.6688204928324;628.5976974736172;8518.87221691675;8744.91255087441;8769.473831570127;8898.510581917928;8923.509206993678;554.431707499816;584.6988800767608;607.890579695659;616.1404840247918;628.3622123544606;4229.238737027365;4245.044864905433;4291.077418355068;4294.162638285567;4375.256206444912;115413;115510.42244788722;115851;119465.40267298782;124963;64329;66173;84251.5763365239;102499.48750256201;104214;91393;91456.54271728599;92264;95752;96152;7216.96391518039;8215.2606265434;8243.752687419386;8609.440386374865;8631.093735157718;49895.75052124716;51146;57238;59787.40212597818;60332.491687708156;74155;75288.62355688187;77022.84465733131;78529.60735196287;80266.59866700628;12071.818922715991;12108.878911211128;12190.817137742764;12324.383780810744;12453.688657783629;14227.359768810635;14310.427582896567;14327.856721432652;14360.138391696113;14413.7837932429;11446.313221206754;12299.69250768738;12907.741845163231;13361.799573006607;13455.46178152863;9526.666566670134;12765.744685106432;13039.686865183645;13290.534831280858;13913;12608.49566017349;12863.742725145632;12870.742585148057;12943.805842912552;13012.609621711365;10624.575016999233;10825.296355736851;10835.02484776374;10981.286216395909;11177.273477223955;10956.726081848017;11411.486483108114;11511.76976460483;11573.88426115728;11573.942130289295 10:08:16 INFO - PROCESS | 1650 | __end_tp_report 10:08:16 INFO - PROCESS | 1650 | __start_cc_report 10:08:16 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2419 10:08:16 INFO - PROCESS | 1650 | __end_cc_report 10:08:16 INFO - PROCESS | 1650 | __startTimestamp1479146896133__endTimestamp 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | ------- Summary: start ------- 10:08:16 INFO - PROCESS | 1650 | Number of tests: 6 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5714.27 Median:5892.53 stddev:676.63 (11.5%) stddev-sans-first:647.88 10:08:16 INFO - PROCESS | 1650 | Values: 3140.5 3261.9 3264.7 3281.1 3314.2 5300.8 5676.8 5695.1 5716.0 5727.1 5628.7 5694.3 5711.5 5732.9 5737.3 5147.7 5186.2 5188.5 5209.7 5257.9 5111.4 5153.9 5157.9 5232.4 5303.0 4351.0 5224.0 5229.2 5238.2 5240.3 5234.0 5239.1 5242.0 5245.4 5262.7 5099.4 5201.5 5203.5 5218.4 5259.3 5088.3 5534.9 5767.0 5784.9 5786.5 6108.9 6167.5 6167.8 6183.4 6190.7 6208.4 6274.1 6284.1 6290.1 6296.9 5763.7 5794.4 5847.5 5865.1 5877.8 6230.5 6306.9 6312.4 6313.4 6330.1 4860.8 6068.9 6093.6 6133.6 6134.5 6160.9 6209.5 6257.1 6264.3 6264.6 6186.6 6244.7 6268.2 6277.5 6277.7 5427.9 6118.3 6124.0 6157.7 6180.7 4973.5 4981.3 4983.7 4985.4 5003.1 3787.8 4462.2 4464.2 4469.8 4476.1 5629.0 5634.9 5673.7 5761.6 5794.6 6290.9 6348.2 6358.4 6367.5 6370.9 5800.0 5822.8 5825.0 5835.6 5855.6 5327.9 5382.1 5442.9 5445.3 5455.0 6203.3 6218.9 6248.6 6289.8 6297.9 6331.6 6352.5 6359.1 6363.0 6374.5 6195.0 6300.7 6322.2 6324.6 6328.2 6046.1 6109.2 6116.4 6123.2 6177.3 5258.9 6230.8 6306.0 6345.2 6347.1 5944.2 6148.3 6181.6 6196.9 6205.3 6174.6 6198.0 6209.8 6216.8 6218.1 4969.6 5844.1 5890.9 5939.1 5977.5 5105.6 5931.4 6005.2 6145.6 6161.8 5892.5 5930.5 5941.7 6003.6 6038.0 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:67020.78 Median:37898.91 stddev:149492.13 (394.4%) stddev-sans-first:149827.98 10:08:16 INFO - PROCESS | 1650 | Values: 469.1 469.7 483.0 504.1 559.7 33601.7 38372.0 38486.6 38694.8 38751.8 39217.6 39685.2 39740.0 39803.8 39883.8 9019.3 9191.0 9209.1 9220.5 9240.2 7892.0 9166.2 9169.4 9174.2 9175.9 9139.5 9197.6 9197.9 9236.8 9247.7 9123.3 9178.4 9188.6 9191.7 9196.0 9061.1 9144.3 9165.7 9170.1 9172.4 31455.8 37859.0 38062.8 38258.0 38259.4 3338.1 3428.9 3527.7 3688.9 3785.4 23519.5 28282.0 29084.8 29423.9 29455.1 3117.5 3122.7 3149.1 3159.7 3174.7 469987.0 481394.0 484421.0 590399.0 592119.0 58033.7 58541.4 58663.4 58680.7 58848.0 32851.3 33089.8 33349.8 61472.7 61790.7 15734.9 15874.0 15893.0 15909.8 15912.9 12101.3 12182.3 12195.2 12204.9 12209.5 17944.8 18032.5 18037.4 18057.6 18154.7 7330.5 7409.7 7413.4 7483.4 7493.3 855.5 918.4 949.9 991.4 1079.4 27278.7 27919.3 28666.9 28761.6 28835.0 1033.7 1036.2 1060.5 1079.6 1088.2 14623.7 14672.6 14912.7 15077.2 15090.0 664218.4 665714.0 666797.7 667495.0 670083.0 52722.0 53320.7 53328.9 53394.0 53445.7 1213.3 1253.7 1325.9 1342.3 1364.7 28446.3 28970.3 29624.6 29685.9 30467.7 45282.3 45844.5 45932.3 46071.8 46071.8 460532.7 460555.0 460595.0 460973.0 463794.0 21506.6 24455.8 25124.2 25192.9 25238.1 43757.0 43767.6 43942.6 44328.6 44473.1 36340.0 36840.0 36843.1 36866.0 37938.8 36267.3 36539.9 37398.8 37406.0 37777.9 35419.5 35726.5 35827.6 36205.6 36462.8 27262.9 31244.4 33081.3 33158.7 33214.5 24303.0 24493.1 24690.3 24741.8 24945.9 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:32529.32 Median:378.72 stddev:95916.74 (25326.9%) stddev-sans-first:96155.40 10:08:16 INFO - PROCESS | 1650 | Values: 364.0 375.5 381.9 393.5 403.9 17264.6 18800.9 19544.6 19675.4 19777.7 19600.7 19743.0 19859.0 20022.8 20161.9 4780.9 5642.8 5774.2 5940.2 5945.1 4041.4 5691.9 5816.9 5829.8 5830.1 4219.7 4284.9 6048.5 6362.0 6376.0 6159.9 6352.5 6428.0 6429.9 6444.3 5614.2 5619.8 5619.8 5621.9 5622.6 19836.4 23685.5 23951.5 23983.6 24333.8 2207.7 2272.8 2844.7 3043.4 3125.3 14670.6 14789.8 14790.0 15175.8 15319.6 1554.8 1603.3 1805.2 1848.1 1885.3 102632.0 102857.5 104837.0 105215.0 105365.0 10572.9 10589.9 10639.9 10706.4 10919.8 13804.8 13814.9 13835.4 13843.9 13885.4 7357.8 8166.8 8457.6 8459.5 8551.0 7242.4 8047.9 8344.7 8524.3 8547.4 5385.5 6626.6 6671.0 6671.4 6678.5 1670.4 1795.3 1835.9 1842.3 1844.7 809.0 853.5 861.8 923.4 945.3 19781.3 20925.4 20980.5 21013.0 21122.6 939.6 952.6 955.2 968.8 1025.4 9530.7 10927.7 11408.0 11472.7 11539.8 556304.0 581084.0 581622.0 591711.0 596219.0 64483.7 88412.0 88821.2 90711.6 90952.5 986.4 993.5 1015.9 1016.9 1018.7 18483.8 18894.4 18947.3 19003.3 19021.7 47158.5 47169.0 47243.5 47495.8 48347.0 62338.0 65926.7 69916.0 74387.6 81450.2 7801.3 7990.0 8066.6 8103.4 8212.7 10423.9 12416.3 12615.4 13088.6 13118.3 9803.9 10681.6 10746.0 10986.5 11200.5 10445.6 10960.3 10978.1 11179.4 11486.6 10597.2 11081.3 11147.7 11412.7 11571.8 8062.8 8152.4 8158.0 8270.1 8290.7 5491.5 5699.0 7788.7 11121.6 11221.2 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5386.39 Median:4898.14 stddev:3004.11 (61.3%) stddev-sans-first:2996.51 10:08:16 INFO - PROCESS | 1650 | Values: 1257.9 1274.0 1459.0 1460.5 1720.8 7491.9 7889.9 8190.1 8434.5 8468.2 8355.1 8435.1 8440.0 8443.5 8465.6 2308.8 2341.7 2344.7 2345.8 2348.5 2323.5 2372.2 2373.4 2376.6 2378.9 1990.7 2317.5 2327.4 2348.6 2354.2 2282.5 2299.5 2315.2 2315.9 2319.4 2116.7 2194.8 2351.8 2358.9 2359.1 8282.1 8351.5 8391.4 8426.3 8432.2 5015.1 5050.6 5208.3 5224.2 5232.0 3210.9 3325.4 3387.6 3407.6 3409.6 1055.0 1072.9 1095.8 1097.4 1102.1 10859.5 10920.2 11090.1 11090.2 11110.9 4267.3 4354.7 4464.4 4469.3 4473.6 8527.0 8642.1 8678.9 8689.0 8702.6 5015.4 5071.4 5082.2 5092.8 5094.5 4065.6 4295.0 4443.6 4449.6 4778.8 3630.8 3646.8 3733.3 3746.5 3749.7 1142.6 1143.9 1148.8 1156.1 1160.9 854.3 878.8 883.1 891.9 904.8 4706.2 5011.5 5047.7 5456.3 5479.1 419.8 428.6 431.8 438.2 458.1 3736.8 3743.6 3745.7 3802.0 3896.8 9926.4 10284.0 10364.9 10367.8 10397.9 9485.4 9669.9 10198.4 10338.8 10339.1 7738.6 7750.0 7833.0 7851.8 8028.1 5537.1 5613.2 5654.7 5770.9 5927.0 7771.4 9278.2 9279.8 9371.0 9382.7 7553.0 9540.9 9589.1 9637.6 9672.8 7151.2 7316.7 7319.6 7325.4 7326.8 7628.0 7909.7 7994.2 8073.3 8077.2 7047.5 7097.9 7134.6 7218.2 7310.5 6998.4 7070.5 7090.3 7114.5 7132.1 6632.6 6693.5 6704.5 6793.8 6813.7 4617.0 4633.2 4673.0 4722.9 4822.3 4811.0 4833.2 4854.3 4942.0 4979.6 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4384.39 Median:5037.00 stddev:1353.50 (26.9%) stddev-sans-first:1322.17 10:08:16 INFO - PROCESS | 1650 | Values: 303.0 306.4 308.9 316.8 318.5 5177.4 5211.2 5235.8 5240.0 5240.4 4863.5 5084.3 5089.2 5136.1 5159.9 3365.3 3767.2 3879.7 3888.7 3957.4 3044.0 3655.1 3944.9 3949.3 3970.3 3901.2 3907.9 3927.8 3950.3 3973.0 3378.3 3440.1 3646.8 3754.9 3869.9 3049.5 3111.0 3150.9 3313.0 3499.2 4211.8 5051.6 5089.2 5092.5 5111.4 3829.2 4188.6 4210.2 4307.6 4598.3 5278.5 5402.5 5435.7 5444.6 5449.8 3850.5 3856.1 3869.7 3883.3 3891.8 5022.4 5678.7 5688.0 5688.2 5726.9 3275.9 3421.9 3909.6 3920.7 3931.8 3991.3 4008.8 4008.9 4011.6 4019.4 3847.9 4562.1 4569.7 4601.8 4612.0 4586.7 5253.7 5393.0 5402.8 5451.0 1181.1 1210.7 1237.8 1254.4 1273.7 789.3 796.3 845.6 879.0 897.7 4295.9 4798.0 4991.9 5092.4 5156.0 4482.5 5598.4 5617.5 5622.4 5647.7 4314.8 4335.0 4911.7 5072.0 5120.4 2738.4 2746.2 2775.3 2778.8 2805.5 4839.7 5086.0 5547.0 5552.7 5562.8 4570.5 5147.8 5431.8 5447.1 5464.9 5332.9 5461.9 5529.0 5548.0 5579.6 4570.6 4636.9 5249.4 5273.6 5386.5 5154.7 5235.7 5288.3 5543.3 5618.7 5884.6 5889.9 5904.6 5931.2 5942.6 3814.5 3821.3 3840.2 3857.3 3865.0 4794.1 5371.8 5675.5 5697.1 5736.7 5521.5 5535.9 5567.4 5569.5 5571.7 4848.6 5293.7 5533.6 5545.7 5566.7 5311.0 5375.8 5381.4 5381.4 5405.6 4159.9 5225.0 5258.7 5357.7 5404.2 4203.0 4509.5 4863.6 5111.7 5410.2 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:20792.63 Median:4429.30 stddev:30906.86 (697.8%) stddev-sans-first:30956.67 10:08:16 INFO - PROCESS | 1650 | Values: 681.7 690.6 706.8 741.2 762.6 14051.6 14585.6 14616.1 14642.0 14658.3 12916.2 12977.8 13005.9 13069.7 13097.3 634.4 658.0 662.6 786.8 814.4 803.9 814.4 816.1 818.8 821.7 860.5 861.9 862.9 863.0 863.7 662.4 855.0 857.4 857.5 858.3 513.8 618.5 638.8 642.5 647.1 13837.8 13882.6 13882.7 13899.7 13917.0 12969.2 13075.9 13128.9 13297.7 13298.9 11978.2 12081.8 12103.9 12358.2 12644.4 4382.5 4409.1 4449.5 4513.1 4550.1 73375.0 89940.6 90823.0 92689.0 93035.5 10416.8 10524.2 10644.1 10780.6 10857.7 20038.1 20280.0 20293.3 20516.9 20720.1 7518.9 7537.2 7558.8 7609.5 7743.8 3898.4 4204.2 4239.9 4489.4 4515.4 3194.8 3283.8 3284.1 3287.2 3287.4 603.5 605.9 613.6 617.5 624.7 535.7 581.8 593.4 607.7 628.6 8518.9 8744.9 8769.5 8898.5 8923.5 554.4 584.7 607.9 616.1 628.4 4229.2 4245.0 4291.1 4294.2 4375.3 115413.0 115510.4 115851.0 119465.4 124963.0 64329.0 66173.0 84251.6 102499.5 104214.0 91393.0 91456.5 92264.0 95752.0 96152.0 7217.0 8215.3 8243.8 8609.4 8631.1 49895.8 51146.0 57238.0 59787.4 60332.5 74155.0 75288.6 77022.8 78529.6 80266.6 12071.8 12108.9 12190.8 12324.4 12453.7 14227.4 14310.4 14327.9 14360.1 14413.8 11446.3 12299.7 12907.7 13361.8 13455.5 9526.7 12765.7 13039.7 13290.5 13913.0 12608.5 12863.7 12870.7 12943.8 13012.6 10624.6 10825.3 10835.0 10981.3 11177.3 10956.7 11411.5 11511.8 11573.9 11573.9 10:08:16 INFO - PROCESS | 1650 | -------- Summary: end -------- 10:08:16 INFO - PROCESS | 1650 | 10:08:16 INFO - TEST-INFO | 1650: exit 0 10:08:16 INFO - TEST-OK | dromaeo_css | took 1119950ms 10:08:16 INFO - TEST-START | kraken 10:08:16 INFO - operating with platform_type : mac_ 10:08:16 INFO - Initialising browser for kraken test... 10:08:16 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpVIYVcv/profile http://localhost:49271/getInfo.html) 10:08:18 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 10:08:19 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 10:08:19 INFO - PROCESS | 1660 | colorDepth:24 10:08:19 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 10:08:19 INFO - PROCESS | 1660 | __metrics 10:08:19 INFO - PROCESS | 1660 | 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" 10:08:19 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:08:20 INFO - TEST-INFO | 1660: exit 0 10:08:20 INFO - Browser initialized. 10:08:20 INFO - Running cycle 1/1 for kraken test... 10:08:20 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpVIYVcv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:32 INFO - PROCESS | 1661 | RSS: Main: 217399296 10:08:32 INFO - PROCESS | 1661 | 10:08:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:35 INFO - PROCESS | 1661 | RSS: Main: 264867840 10:08:35 INFO - PROCESS | 1661 | 10:08:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 10:08:39 INFO - PROCESS | 1661 | RSS: Main: 277622784 10:08:39 INFO - PROCESS | 1661 | 10:08:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 10:08:42 INFO - PROCESS | 1661 | RSS: Main: 374915072 10:08:42 INFO - PROCESS | 1661 | 10:08:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 10:08:45 INFO - PROCESS | 1661 | RSS: Main: 278802432 10:08:45 INFO - PROCESS | 1661 | 10:08:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:48 INFO - PROCESS | 1661 | RSS: Main: 279769088 10:08:48 INFO - PROCESS | 1661 | 10:08:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:51 INFO - PROCESS | 1661 | RSS: Main: 321232896 10:08:51 INFO - PROCESS | 1661 | 10:08:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:55 INFO - PROCESS | 1661 | RSS: Main: 320790528 10:08:55 INFO - PROCESS | 1661 | 10:08:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 10:08:58 INFO - PROCESS | 1661 | RSS: Main: 317370368 10:08:58 INFO - PROCESS | 1661 | 10:09:00 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:09:00 INFO - PROCESS | 1661 | RSS: Main: 296267776 10:09:00 INFO - PROCESS | 1661 | 10:09:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:09:03 INFO - PROCESS | 1661 | RSS: Main: 298926080 10:09:03 INFO - PROCESS | 1661 | 10:09:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:09:06 INFO - PROCESS | 1661 | RSS: Main: 301391872 10:09:06 INFO - PROCESS | 1661 | 10:09:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:09:09 INFO - PROCESS | 1661 | RSS: Main: 316121088 10:09:09 INFO - PROCESS | 1661 | 10:09:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:13 INFO - PROCESS | 1661 | RSS: Main: 310067200 10:09:13 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 10:09:15 INFO - PROCESS | 1661 | RSS: Main: 354091008 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | __start_tp_report 10:09:15 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 10:09:15 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 10:09:15 INFO - PROCESS | 1661 | |i|pagename|runs| 10:09:15 INFO - PROCESS | 1661 | |0;ai-astar;104;115;115;113;113;113;113;112;114;113 10:09:15 INFO - PROCESS | 1661 | |1;audio-beat-detection;139;112;114;147;146;139;110;112;142;111 10:09:15 INFO - PROCESS | 1661 | |2;audio-dft;184;184;183;189;182;184;184;185;187;185 10:09:15 INFO - PROCESS | 1661 | |3;audio-fft;81;58;59;89;62;58;58;71;110;82 10:09:15 INFO - PROCESS | 1661 | |4;audio-oscillator;86;69;94;69;68;70;88;67;69;118 10:09:15 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;117;130;89;84;86;85;88;85;94;131 10:09:15 INFO - PROCESS | 1661 | |6;imaging-darkroom;96;140;149;102;91;92;92;93;93;93 10:09:15 INFO - PROCESS | 1661 | |7;imaging-desaturate;81;85;84;83;81;82;84;85;84;82 10:09:15 INFO - PROCESS | 1661 | |8;json-parse-financial;58;55;56;56;58;57;58;56;56;56 10:09:15 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;66;74;72;74;70;68;70;70;71;71 10:09:15 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;84;84;83;82;84;88;81;86;84;84 10:09:15 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;145;146;148;146;148;143;152;149;201;141 10:09:15 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;168;167;168;167;166;166;170;167;164;166 10:09:15 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;67;67;66;67;66;66;66;66;66;65 10:09:15 INFO - PROCESS | 1661 | __end_tp_report 10:09:15 INFO - PROCESS | 1661 | __start_cc_report 10:09:15 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1986 10:09:15 INFO - PROCESS | 1661 | __end_cc_report 10:09:15 INFO - PROCESS | 1661 | __startTimestamp1479146955316__endTimestamp 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | ------- Summary: start ------- 10:09:15 INFO - PROCESS | 1661 | Number of tests: 14 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 10:09:15 INFO - PROCESS | 1661 | Values: 104.0 115.0 115.0 113.0 113.0 113.0 113.0 112.0 114.0 113.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:139.00 stddev:16.46 (11.8%) stddev-sans-first:16.89 10:09:15 INFO - PROCESS | 1661 | Values: 139.0 112.0 114.0 147.0 146.0 139.0 110.0 112.0 142.0 111.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:184.70 Median:184.50 stddev:2.00 (1.1%) stddev-sans-first:2.11 10:09:15 INFO - PROCESS | 1661 | Values: 184.0 184.0 183.0 189.0 182.0 184.0 184.0 185.0 187.0 185.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:72.80 Median:66.50 stddev:17.53 (26.4%) stddev-sans-first:18.34 10:09:15 INFO - PROCESS | 1661 | Values: 81.0 58.0 59.0 89.0 62.0 58.0 58.0 71.0 110.0 82.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:69.50 stddev:16.72 (24.1%) stddev-sans-first:17.58 10:09:15 INFO - PROCESS | 1661 | Values: 86.0 69.0 94.0 69.0 68.0 70.0 88.0 67.0 69.0 118.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:98.90 Median:85.50 stddev:19.27 (22.5%) stddev-sans-first:19.29 10:09:15 INFO - PROCESS | 1661 | Values: 117.0 130.0 89.0 84.0 86.0 85.0 88.0 85.0 94.0 131.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:92.50 stddev:21.63 (23.4%) stddev-sans-first:22.74 10:09:15 INFO - PROCESS | 1661 | Values: 96.0 140.0 149.0 102.0 91.0 92.0 92.0 93.0 93.0 93.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:83.10 Median:84.00 stddev:1.52 (1.8%) stddev-sans-first:1.41 10:09:15 INFO - PROCESS | 1661 | Values: 81.0 85.0 84.0 83.0 81.0 82.0 84.0 85.0 84.0 82.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:56.50 stddev:1.07 (1.9%) stddev-sans-first:1.01 10:09:15 INFO - PROCESS | 1661 | Values: 58.0 55.0 56.0 56.0 58.0 57.0 58.0 56.0 56.0 56.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:2.46 (3.5%) stddev-sans-first:1.96 10:09:15 INFO - PROCESS | 1661 | Values: 66.0 74.0 72.0 74.0 70.0 68.0 70.0 70.0 71.0 71.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:84.00 Median:84.00 stddev:1.94 (2.3%) stddev-sans-first:2.06 10:09:15 INFO - PROCESS | 1661 | Values: 84.0 84.0 83.0 82.0 84.0 88.0 81.0 86.0 84.0 84.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:148.00 stddev:17.53 (11.8%) stddev-sans-first:18.41 10:09:15 INFO - PROCESS | 1661 | Values: 145.0 146.0 148.0 146.0 148.0 143.0 152.0 149.0 201.0 141.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.00 stddev:1.60 (1.0%) stddev-sans-first:1.64 10:09:15 INFO - PROCESS | 1661 | Values: 168.0 167.0 168.0 167.0 166.0 166.0 170.0 167.0 164.0 166.0 10:09:15 INFO - PROCESS | 1661 | 10:09:15 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 10:09:15 INFO - PROCESS | 1661 | Values: 67.0 67.0 66.0 67.0 66.0 66.0 66.0 66.0 66.0 65.0 10:09:15 INFO - PROCESS | 1661 | -------- Summary: end -------- 10:09:15 INFO - PROCESS | 1661 | 10:09:16 INFO - TEST-INFO | 1661: exit 0 10:09:16 INFO - TEST-OK | kraken | took 59488ms 10:09:16 INFO - SUITE-END | took 1179s 10:09:16 INFO - Completed test suite (00:19:39) 10:09:16 INFO - javascript benchmark 10:09:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3140.450421176294, 3261.8695252189914, 3264.7388208943294, 3281.0976981330145, 3314.171457135716, 5300.761465734042, 5676.772929082842, 5695.145728140781, 5715.971420142904, 5727.140928860673, 5628.690422026787, 5694.288213973254, 5711.485966263035, 5732.885342293152, 5737.340205876326, 5147.6654017488745, 5186.222066689989, 5188.533032027117, 5209.687418754868, 5257.868553286179, 5111.361079865017, 5153.922691159617, 5157.871053223662, 5232.424433312332, 5303.045451818671, 4350.956490435086, 5224.0, 5229.163333866582, 5238.214267859814, 5240.34495688039, 5233.973830130844, 5239.056969745444, 5242.0, 5245.370555533323, 5262.684238945675, 5099.388073431175, 5201.453847346012, 5203.4796520348045, 5218.425973142951, 5259.316288882459, 5088.313077734496, 5534.889302213933, 5767.019606666835, 5784.855378615526, 5786.450287222707, 6108.858142493672, 6167.506599472076, 6167.845803854894, 6183.412575805291, 6190.659513726743, 6208.441240288395, 6274.121622972787, 6284.12022316876, 6290.119383286344, 6296.87406251877, 5763.717398973472, 5794.362620111784, 5847.532197424196, 5865.120231965197, 5877.764889404419, 6230.470410015108, 6306.87386252277, 6312.43188113072, 6313.368663133741, 6330.145430366933, 4860.756962151878, 6068.908966365508, 6093.634381937098, 6133.591519773218, 6134.528431832502, 6160.866517872734, 6209.472194863397, 6257.0614407838275, 6264.310925798159, 6264.624122552661, 6186.566940314135, 6244.687765611701, 6268.216472940882, 6277.52918531112, 6277.654728989858, 5427.945720542823, 6118.284172697687, 6124.0, 6157.6613286269685, 6180.6909654517995, 4973.502649734997, 4981.32752078472, 4983.651144419929, 4985.376827896513, 5003.099442100457, 3787.8484860605818, 4462.152191083684, 4464.15181115587, 4469.776511174493, 4476.149531588988, 5629.014922388647, 5634.915476267859, 5673.74468148926, 5761.596688231867, 5794.565407594448, 6290.937090629127, 6348.206474190726, 6358.364163583605, 6367.458766004941, 6370.872582548323, 5799.956007918531, 5822.7670893163795, 5824.95150872847, 5835.572917187057, 5855.560832937546, 5327.893442131136, 5382.1119515280325, 5442.91835622466, 5445.346558412936, 5455.0, 6203.286622038523, 6218.9378106218355, 6248.625082495065, 6289.779857704958, 6297.874042519215, 6331.5884467511305, 6352.491800656029, 6359.141515895295, 6362.981922892315, 6374.458171055327, 6194.969025154845, 6300.71646775887, 6322.209723784527, 6324.557280990287, 6328.177336946167, 6046.062860256668, 6109.222480666452, 6116.418940200674, 6123.221615589998, 6177.320494745484, 5258.868528286824, 6230.844228894314, 6306.0, 6345.238571371465, 6347.111404403295, 5944.1975333331175, 6148.323684394623, 6181.567290289637, 6196.938030619816, 6205.286392064971, 6174.598651087665, 6198.0, 6209.782657607143, 6216.844578885564, 6218.067289906369, 4969.577585905238, 5844.12338149281, 5890.882182356415, 5939.138824870283, 5977.491913187428, 5105.591552675852, 5931.4068593142065, 6005.189299444519, 6145.617236121837, 6161.753529858755, 5892.528597712087, 5930.495907847709, 5941.67320797343, 6003.6397816131175, 6037.969810150921], "value": 5669.940776953461, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.05485446824656, 469.713474780384, 482.97127119236063, 504.0951492071731, 559.734126290012, 33601.66398336016, 38372.0, 38486.61513384865, 38694.8391548254, 38751.8374448767, 39217.60782392175, 39685.206295874064, 39740.006499837444, 39803.80098099505, 39883.8034858954, 9019.323550733689, 9191.034941331165, 9209.079092090804, 9220.492872891988, 9240.214581760525, 7892.0, 9166.220871225954, 9169.403988740743, 9174.220191283748, 9175.908240917606, 9139.543022848864, 9197.586108625128, 9197.862032069525, 9236.815263694722, 9247.722568322928, 9123.270138388945, 9178.357514973955, 9188.63245470187, 9191.724248272563, 9195.954020229923, 9061.13919177677, 9144.268458523302, 9165.725028249164, 9170.082991700776, 9172.44965302084, 31455.842720786248, 37859.0, 38062.80968595139, 38258.0, 38259.42610860839, 3338.115399419156, 3428.879989200366, 3527.7177825773874, 3688.926221475582, 3785.3943369060817, 23519.529609407888, 28282.010129645365, 29084.836606535715, 29423.852880735674, 29455.116346509425, 3117.547955546433, 3122.73456756176, 3149.1497295730023, 3159.7472202223767, 3174.666660000713, 469987.0, 481394.0, 484421.0, 590398.9999999957, 592119.0, 58033.709831451415, 58541.41458585445, 58663.41336586665, 58680.7065964676, 58848.0, 32851.3429731404, 33089.834550827094, 33349.83325083359, 61472.69263653653, 61790.67465277387, 15734.898557101122, 15874.0, 15893.0, 15909.840901591067, 15912.92043539775, 12101.273923564615, 12182.269063856196, 12195.207311524722, 12204.877951220375, 12209.450574724158, 17944.820551794313, 18032.459026229233, 18037.368692095977, 18057.638847223243, 18154.727679084823, 7330.486865919441, 7409.703611855465, 7413.406927445792, 7483.438742094256, 7493.288137626917, 855.4653341661461, 918.3525614441802, 949.886013678363, 991.3506653142204, 1079.379356869803, 27278.727212728016, 27919.302017449725, 28666.856665716954, 28761.56857647137, 28835.0, 1033.7467320506523, 1036.1555332404066, 1060.4538662588773, 1079.578964203961, 1088.1675518228562, 14623.707525849424, 14672.633184170481, 14912.70174596502, 15077.246137693071, 15090.0, 664218.4312627441, 665714.0, 666797.6660116669, 667495.0, 670083.0, 52722.0, 53320.73339633277, 53328.933421331356, 53394.0, 53445.7327713359, 1213.2962881528692, 1253.6552448076852, 1325.8796317112026, 1342.2550484481012, 1364.6656569140623, 28446.288842779097, 28970.27574310659, 29624.555631665113, 29685.85157074201, 30467.695323047374, 45282.32076518921, 45844.54155458403, 45932.311015335465, 46071.76964115158, 46071.848203795176, 460532.69733652455, 460555.0, 460595.0, 460973.0, 463794.0, 21506.569868602226, 24455.75544244535, 25124.24627261185, 25192.874035629706, 25238.116665916605, 43757.0, 43767.56232437635, 43942.56057439385, 44328.556714432445, 44473.110537788416, 36340.0, 36840.0, 36843.07892302714, 36866.0, 37938.810305949395, 36267.27465450623, 36539.903802884895, 37398.8130059348, 37406.0, 37777.866664000125, 35419.468707969914, 35726.464103039, 35827.641723582434, 36205.637943620226, 36462.817685912465, 27262.863685682238, 31244.375112498077, 33081.33837323288, 33158.66841331556, 33214.5017824728, 24303.027878884648, 24493.142740004016, 24690.25929222126, 24741.752582474666, 24945.875270624256], "value": 18937.42874422045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.96906262967644, 375.53996354465784, 381.89116101910963, 393.5316972802363, 403.90710136668565, 17264.568385790346, 18800.90599547004, 19544.609107817854, 19675.409737707872, 19777.703334449958, 19600.70598941017, 19743.0, 19859.01409859017, 20022.799772002276, 20161.899190504028, 4780.880477988061, 5642.830715078554, 5774.249347584829, 5940.198073260098, 5945.137954996523, 4041.4139949707273, 5691.886162276752, 5816.854578635525, 5829.766809327643, 5830.125481177814, 4219.725717828332, 4284.914301713964, 6048.546359023069, 6362.0456931460185, 6375.968120159416, 6159.938400616026, 6352.523560732917, 6427.967860160693, 6429.903551446732, 6444.258910225314, 5614.242077319551, 5619.775208991636, 5619.803306884281, 5621.887562248732, 5622.550195984311, 19836.404907852644, 23685.526289474285, 23951.520969580684, 23983.640245396335, 24333.756662433327, 2207.701960235364, 2272.761360057203, 2844.6586409630977, 3043.421749867518, 3125.3280544683003, 14670.559883203521, 14789.778153327708, 14790.038647487987, 15175.848241517553, 15319.617009574738, 1554.8289688134296, 1603.2624992503463, 1805.1696219738935, 1848.0851978270841, 1885.2930151193302, 102631.97368026373, 102857.48571257171, 104837.0, 105215.0, 105365.0, 10572.947135264274, 10589.947050264853, 10639.946800265949, 10706.357618542912, 10919.781604368027, 13804.792928106086, 13814.93092534551, 13835.446582136803, 13843.86156138446, 13885.4445822168, 7357.822748360237, 8166.7958301042945, 8457.619407126695, 8459.450135741157, 8551.033262349694, 7242.384397326181, 8047.8732977383215, 8344.707935222359, 8524.275436587835, 8547.35894807892, 5385.461453854583, 6626.602403855784, 6671.0, 6671.39957403836, 6678.460415979539, 1670.4153546258713, 1795.2729144696418, 1835.8898466092078, 1842.2723024405284, 1844.6994868617592, 808.9797755056171, 853.5476197615274, 861.8491763941286, 923.4182465046978, 945.262695097811, 19781.307654232034, 20925.37223883286, 20980.475488112614, 21013.0, 21122.577548448946, 939.5584075484511, 952.5618215620892, 955.2310390136007, 968.7529679931572, 1025.415513157508, 9530.666426675032, 10927.726806829893, 11408.030317423112, 11472.713182170513, 11539.769204616028, 556304.0, 581084.0, 581622.0, 591711.0, 596219.0, 64483.67758161273, 88412.0, 88821.22357552813, 90711.63932541017, 90952.54523727339, 986.3539381704851, 993.4883534979491, 1015.8780946286493, 1016.9033941775667, 1018.7351288665001, 18483.815161848208, 18894.433167005012, 18947.33684321042, 19003.334883279018, 19021.71467427962, 47158.528414715416, 47169.0, 47243.52756472391, 47495.81260468516, 48347.0, 62338.0, 65926.67036664786, 69916.0, 74387.62806185934, 81450.18549814663, 7801.336886364723, 7990.0, 8066.596670166586, 8103.351731861555, 8212.712555060543, 10423.895761042293, 12416.317102559446, 12615.36923153857, 13088.607341779762, 13118.278494682883, 9803.921568627587, 10681.57273709043, 10746.0, 10986.505607247853, 11200.495977681187, 10445.582176712845, 10960.28758130719, 10978.12175026012, 11179.385133817717, 11486.597969071043, 10597.152227821602, 11081.27971681838, 11147.721306967067, 11412.717456508566, 11571.768564628828, 8062.798430039296, 8152.429329947084, 8158.0, 8270.090290067967, 8290.668373265002, 5491.450854914477, 5699.0, 7788.727394541163, 11121.610743623934, 11221.214514983873], "value": 9352.573960609572, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1257.9433925473352, 1273.9617811465657, 1459.0224549551804, 1460.4596299369234, 1720.7849018872632, 7491.8876216856725, 7889.8816517752275, 8190.099089100202, 8434.53610051447, 8468.153184681529, 8355.080941096474, 8435.114312997139, 8440.029396619391, 8443.451175673577, 8465.619047142878, 2308.803751681109, 2341.695579574653, 2344.6951896253463, 2345.8240631952585, 2348.471593891378, 2323.53529294141, 2372.2408829174583, 2373.3947843299934, 2376.572217000939, 2378.940526486843, 1990.691442826357, 2317.455397981473, 2327.359976006603, 2348.5791096386697, 2354.21133920137, 2282.5116555897102, 2299.494111295512, 2315.166540045599, 2315.918942837001, 2319.3621754017613, 2116.6613341865227, 2194.846360754748, 2351.8000969917407, 2358.905643774247, 2359.1153317505937, 8282.088970213272, 8351.498910065415, 8391.370647201484, 8426.283765879907, 8432.198941100585, 5015.0722116408215, 5050.621203409759, 5208.322918020671, 5224.242484839715, 5231.973840130775, 3210.871565137392, 3325.384803811303, 3387.6442973487674, 3407.6421975692692, 3409.590849098099, 1054.9683509494728, 1072.8917676765193, 1095.7863216672752, 1097.4238524774478, 1102.079763397564, 10859.511321990529, 10920.235583509157, 11090.112790976702, 11090.168237382148, 11110.888891111146, 4267.317229243306, 4354.6951713380395, 4464.441944756905, 4469.284914413678, 4473.619742321939, 8527.019392769911, 8642.09258027898, 8678.86981695275, 8688.95655521731, 8702.608382622797, 5015.3730783652045, 5071.39143302806, 5082.186850103965, 5092.8472145835685, 5094.541491265803, 4065.593440655911, 4295.0, 4443.622292105143, 4449.599536041772, 4778.761061946958, 3630.8184590770356, 3646.781193128421, 3733.3466643337306, 3746.4754934309217, 3749.662530372225, 1142.5943789954613, 1143.937083460401, 1148.833419154218, 1156.0982433701727, 1160.8723040465543, 854.2567965869733, 878.7759121424124, 883.1124719656832, 891.9018907920124, 904.7602385367883, 4706.247000479975, 5011.473795251446, 5047.712833227503, 5456.290682211287, 5479.123340265538, 419.7943007926155, 428.5842732549422, 431.7905815679393, 438.2352794373838, 458.1386992454165, 3736.831842567037, 3743.5694895086867, 3745.6816170625257, 3802.049487628093, 3896.844126234919, 9926.404415735078, 10284.0, 10364.896351036545, 10367.792644147075, 10397.896021039844, 9485.430874147572, 9669.90330096704, 10198.388096714221, 10338.793224135476, 10339.0694837465, 7738.574378409242, 7750.031246094239, 7832.960835195788, 7851.803704907309, 8028.11690714021, 5537.1140617501005, 5613.186088017296, 5654.6889921054735, 5770.884582308414, 5927.0, 7771.378289736695, 9278.211352034883, 9279.814403712024, 9370.953145234504, 9382.67160649374, 7553.0, 9540.904590954002, 9589.089036541518, 9637.614495420105, 9672.806543869225, 7151.213366529573, 7316.707331706672, 7319.59742214162, 7325.377342925911, 7326.780196593898, 7627.961860190663, 7909.7231596893835, 7994.240547148244, 8073.313768329758, 8077.151899050515, 7047.47143964215, 7097.893531596927, 7134.643267836484, 7218.205997340184, 7310.524815887163, 6998.440124789903, 7070.469714771516, 7090.255523170199, 7114.466415018997, 7132.108486439195, 6632.635205063768, 6693.497987650849, 6704.497162712719, 6793.762218322335, 6813.727450901908, 4616.976915115403, 4633.166030114612, 4673.0, 4722.932906424119, 4822.300766388784, 4811.013742182914, 4833.2025215840395, 4854.271859221145, 4942.0, 4979.576735977483], "value": 4275.789298488675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.9878804847806, 306.40710225713246, 308.9258577941294, 316.7640108119451, 318.47928636679023, 5177.430482646911, 5211.166213405856, 5235.795766973599, 5239.973800130994, 5240.44975277596, 4863.537963893425, 5084.262781896623, 5089.236614507826, 5136.126858434066, 5159.922601160985, 3365.276465559898, 3767.1900541383598, 3879.72841901067, 3888.6889048876164, 3957.3866050762035, 3043.9543406848916, 3655.104499397638, 3944.9211015779665, 3949.269385163754, 3970.265500882346, 3901.239258344624, 3907.863224787133, 3927.7643341399466, 3950.3481925482406, 3972.9602703973023, 3378.2736711606876, 3440.0539851540775, 3646.751534462348, 3754.9436758448646, 3869.8839034829, 3049.4815881300233, 3110.9844450777828, 3150.9444336147417, 3312.950305745416, 3499.160201551609, 4211.831526738927, 5051.570616497602, 5089.236614507853, 5092.541671249568, 5111.437741848394, 3829.2341531693774, 4188.5811418858175, 4210.157968406331, 4307.633851122659, 4598.310253461974, 5278.47215278473, 5402.540784033362, 5435.673859568399, 5444.646098003617, 5449.754761035763, 3850.4994350734505, 3856.0745421099014, 3869.7423337415453, 3883.262180185756, 3891.8248678809523, 5022.425108129973, 5678.687672178028, 5687.971560142173, 5688.232088668018, 5726.907469966213, 3275.901722948268, 3421.9486707699402, 3909.58949310324, 3920.7059470539825, 3931.783751893616, 3991.3214753491975, 4008.779517126586, 4008.8797336079383, 4011.6389524942897, 4019.3569028955226, 3847.8845634631007, 4562.064776720765, 4569.702969306984, 4601.815927362935, 4611.953880461152, 4586.747728874877, 5253.737313134328, 5393.0, 5402.810901618424, 5451.0, 1181.1141643767176, 1210.680358409338, 1237.8452693413324, 1254.3728135932033, 1273.6815796050987, 789.2935822438885, 796.3231253434534, 845.6363763581719, 878.9560521973875, 897.708244820436, 4295.926018495376, 4797.976010119927, 4991.8535029937475, 5092.363454568179, 5156.0, 4482.484514280899, 5598.356189038232, 5617.466340697627, 5622.381538030813, 5647.661140331593, 4314.7626880521875, 4335.0, 4911.656184067152, 5071.974640126849, 5120.436751957282, 2738.3975525384385, 2746.231055304478, 2775.34779326858, 2778.7776977841722, 2805.495010898017, 4839.685420447659, 5085.974570127126, 5547.0, 5552.666839989613, 5562.777488900399, 4570.542945705402, 5147.819826306061, 5431.8098866539485, 5447.073997420448, 5464.9453505465235, 5332.920315958295, 5461.890762184814, 5528.9723551381985, 5548.0, 5579.553635709133, 4570.565796249351, 4636.86089417318, 5249.422563517934, 5273.551748101454, 5386.461353864739, 5154.716490593053, 5235.65968212065, 5288.2860813791185, 5543.25166102571, 5618.6628802272, 5884.645751921364, 5889.882202355843, 5904.557158213065, 5931.220633809753, 5942.603488180188, 3814.5422549294262, 3821.293060783764, 3840.1743625120735, 3857.267119247334, 3864.9806750966063, 4794.089123066746, 5371.785128594813, 5675.517581005661, 5697.116946873241, 5736.71316434185, 5521.5306698929435, 5535.916961245504, 5567.387587365306, 5569.526590239875, 5571.749271282721, 4848.587870031087, 5293.708846013506, 5533.6403133796175, 5545.694986775766, 5566.68982372311, 5310.9734451327495, 5375.838724838261, 5381.408045115112, 5381.448244158836, 5405.567524605904, 4159.937600935928, 5225.047847965887, 5258.737063146751, 5357.705326207096, 5404.21682833349, 4202.965153452719, 4509.458864936229, 4863.641134050054, 5111.718855462986, 5410.161424979135], "value": 3954.126933474936, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.7170874087254, 690.5787469643517, 706.8303607134286, 741.2217171969432, 762.6034462079718, 14051.648708782286, 14585.645301580644, 14616.123032618023, 14642.0, 14658.267086645665, 12916.16044957077, 12977.805332920014, 13005.934970325136, 13069.67325816855, 13097.345132743372, 634.3814780588935, 658.0294066252287, 662.5792621685216, 786.7836345005112, 814.3851392198882, 803.9035315762087, 814.3729328417115, 816.147126253064, 818.8485130250923, 821.7165078048064, 860.5439117267858, 861.9051904290523, 862.8835107260534, 862.9611667474978, 863.7495126413332, 662.4104546953215, 855.0380821575727, 857.3698331726176, 857.4598003257908, 858.2618947704968, 513.7508308470389, 618.5144661440793, 638.773235501395, 642.5245318464349, 647.1360733420888, 13837.792433113511, 13882.583522494342, 13882.722345553133, 13899.722005559832, 13917.0, 12969.221846689228, 13075.934620326838, 13128.934355328258, 13297.667558311023, 13298.933505332509, 11978.221415607957, 12081.758364832742, 12103.93948030263, 12358.196717213352, 12644.367781610883, 4382.452193475816, 4409.074094440171, 4449.5327990560845, 4513.074819661962, 4550.112728018038, 73375.0, 89940.55029724874, 90823.0, 92689.0, 93035.53482232614, 10416.843747343795, 10524.210684198715, 10644.148468122532, 10780.568777248822, 10857.728556786144, 20038.118684111167, 20280.0, 20293.28973485921, 20516.897415512827, 20720.06759695817, 7518.887216691754, 7537.2085930977555, 7558.811029724301, 7609.505382150142, 7743.7676869694, 3898.3567711327314, 4204.159168166379, 4239.851605193865, 4489.371487991683, 4515.412996310524, 3194.776365654382, 3283.8193899335765, 3284.080457471912, 3287.178205448638, 3287.408266512051, 603.5081408652022, 605.8606520500222, 613.6440864298696, 617.5121653893464, 624.7188765055743, 535.7455208775879, 581.7614777941109, 593.4362355762044, 607.6688204928324, 628.5976974736172, 8518.87221691675, 8744.91255087441, 8769.473831570127, 8898.510581917928, 8923.509206993678, 554.431707499816, 584.6988800767608, 607.890579695659, 616.1404840247918, 628.3622123544606, 4229.238737027365, 4245.044864905433, 4291.077418355068, 4294.162638285567, 4375.256206444912, 115413.0, 115510.42244788722, 115851.0, 119465.40267298782, 124963.0, 64329.0, 66173.0, 84251.5763365239, 102499.48750256201, 104214.0, 91393.0, 91456.54271728599, 92264.0, 95752.0, 96152.0, 7216.96391518039, 8215.2606265434, 8243.752687419386, 8609.440386374865, 8631.093735157718, 49895.75052124716, 51146.0, 57238.0, 59787.40212597818, 60332.491687708156, 74155.0, 75288.62355688187, 77022.84465733131, 78529.60735196287, 80266.59866700628, 12071.818922715991, 12108.878911211128, 12190.817137742764, 12324.383780810744, 12453.688657783629, 14227.359768810635, 14310.427582896567, 14327.856721432652, 14360.138391696113, 14413.7837932429, 11446.313221206754, 12299.69250768738, 12907.741845163231, 13361.799573006607, 13455.46178152863, 9526.666566670134, 12765.744685106432, 13039.686865183645, 13290.534831280858, 13913.0, 12608.49566017349, 12863.742725145632, 12870.742585148057, 12943.805842912552, 13012.609621711365, 10624.575016999233, 10825.296355736851, 10835.02484776374, 10981.286216395909, 11177.273477223955, 10956.726081848017, 11411.486483108114, 11511.76976460483, 11573.88426115728, 11573.942130289295], "value": 7221.511207068328, "unit": "score"}], "name": "dromaeo_css", "value": 7048.481757047366, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 115.0, 113.0, 113.0, 113.0, 113.0, 112.0, 114.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 112.0, 114.0, 147.0, 146.0, 139.0, 110.0, 112.0, 142.0, 111.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 189.0, 182.0, 184.0, 184.0, 185.0, 187.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 89.0, 62.0, 58.0, 58.0, 71.0, 110.0, 82.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 94.0, 69.0, 68.0, 70.0, 88.0, 67.0, 69.0, 118.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 130.0, 89.0, 84.0, 86.0, 85.0, 88.0, 85.0, 94.0, 131.0], "value": 98.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 140.0, 149.0, 102.0, 91.0, 92.0, 92.0, 93.0, 93.0, 93.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 84.0, 83.0, 81.0, 82.0, 84.0, 85.0, 84.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 56.0, 56.0, 58.0, 57.0, 58.0, 56.0, 56.0, 56.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 72.0, 74.0, 70.0, 68.0, 70.0, 70.0, 71.0, 71.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 82.0, 84.0, 88.0, 81.0, 86.0, 84.0, 84.0], "value": 84.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 148.0, 146.0, 148.0, 143.0, 152.0, 149.0, 201.0, 141.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 168.0, 167.0, 166.0, 166.0, 170.0, 167.0, 164.0, 166.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1459.3000000000004, "alertThreshold": 2.0}]} 10:09:16 INFO - Return code: 0 10:09:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:09:16 ERROR - # TBPL SUCCESS # 10:09:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:09:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:09:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:09:16 INFO - 2016-11-14 10:09:16.666 screenresolution[1665:9875] starting screenresolution argv=screenresolution get 10:09:16 INFO - 2016-11-14 10:09:16.669 screenresolution[1665:9875] Display 0: 1600x1200x32@60 10:09:16 INFO - 2016-11-14 10:09:16.675 screenresolution[1666:9878] starting screenresolution argv=screenresolution list 10:09:16 INFO - Available Modes on Display 0 10:09:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:09:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:09:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:09:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:09:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:09:16 INFO - Intel Iris: 10:09:16 INFO - Chipset Model: Intel Iris 10:09:16 INFO - Type: GPU 10:09:16 INFO - Bus: Built-In 10:09:16 INFO - VRAM (Dynamic, Max): 1536 MB 10:09:16 INFO - Vendor: Intel (0x8086) 10:09:16 INFO - Device ID: 0x0a2e 10:09:16 INFO - Revision ID: 0x0009 10:09:16 INFO - Displays: 10:09:16 INFO - Display: 10:09:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:09:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:09:16 INFO - Display Serial Number: 1600x1200 60 10:09:16 INFO - Main Display: Yes 10:09:16 INFO - Mirror: Off 10:09:16 INFO - Online: Yes 10:09:16 INFO - Rotation: Supported 10:09:16 INFO - Return code: 0 10:09:16 INFO - Running post-action listener: _resource_record_post_action 10:09:16 INFO - [mozharness: 2016-11-14 18:09:16.911822Z] Finished run-tests step (success) 10:09:16 INFO - Running post-run listener: _resource_record_post_run 10:09:16 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 36770816; Write bytes: 652634624; Read time: 372; Write time: 1830 10:09:16 INFO - TinderboxPrint: CPU usage
53.6% 10:09:16 INFO - TinderboxPrint: I/O read bytes / time
36,770,816 / 372 10:09:16 INFO - TinderboxPrint: I/O write bytes / time
652,634,624 / 1,830 10:09:16 INFO - TinderboxPrint: CPU idle
2,249.9 (46.4%) 10:09:16 INFO - TinderboxPrint: CPU system
971.9 (20.1%) 10:09:16 INFO - TinderboxPrint: CPU user
1,623.8 (33.5%) 10:09:16 INFO - TinderboxPrint: Swap in / out
389,615,616 / 0 10:09:16 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346423808; Write bytes: 345131008; Read time: 26538; Write time: 641 10:09:16 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 35771392; Write bytes: 297480704; Read time: 354; Write time: 1156 10:09:17 INFO - Running post-run listener: _upload_blobber_files 10:09:17 INFO - Blob upload gear active. 10:09:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:09:17 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 10:09:17 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'] 10:09:17 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 10:09:17 INFO - (blobuploader) - INFO - Open directory for files ... 10:09:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:09:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:09:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:18 INFO - (blobuploader) - INFO - Done attempting. 10:09:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:09:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:09:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:20 INFO - (blobuploader) - INFO - Done attempting. 10:09:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:09:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:09:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:09:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:09:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:09:22 INFO - (blobuploader) - INFO - Done attempting. 10:09:22 INFO - (blobuploader) - INFO - Iteration through files over. 10:09:22 INFO - Return code: 0 10:09:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:09:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:09:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbf066dc008374d1091fb607ac999326e654568b59ec7dc34c24fa26977cd51042adb7c6d9156ceacaf1a37cfa2ea9a319f30352eb026622cce365024f82b360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ece3feea68d01b37cbc4a8fcbe6b700e0faecc0709437de85cfa2836b973b8c060d340c666d0144bff35a372eef0718b5e21931e1acfaa80b00274c9e5d55a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef797e7dabba7dd97f08c054f8dbce5a1efcabf1d2148e53a98d396202fa0036291400d77de4c718dab2ec719e04ea1460c05e700341673885d888a7e5ba7b23"} 10:09:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:09:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:09:22 INFO - Contents: 10:09:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fbf066dc008374d1091fb607ac999326e654568b59ec7dc34c24fa26977cd51042adb7c6d9156ceacaf1a37cfa2ea9a319f30352eb026622cce365024f82b360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ece3feea68d01b37cbc4a8fcbe6b700e0faecc0709437de85cfa2836b973b8c060d340c666d0144bff35a372eef0718b5e21931e1acfaa80b00274c9e5d55a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef797e7dabba7dd97f08c054f8dbce5a1efcabf1d2148e53a98d396202fa0036291400d77de4c718dab2ec719e04ea1460c05e700341673885d888a7e5ba7b23"} 10:09:22 INFO - Running post-run listener: copy_logs_to_upload_dir 10:09:22 INFO - Copying logs to upload dir... 10:09:22 INFO - mkdir: /builds/slave/test/build/upload/logs 10:09:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.836555 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-14 10:09:22.419996) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:22.423115) ========= 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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/fbf066dc008374d1091fb607ac999326e654568b59ec7dc34c24fa26977cd51042adb7c6d9156ceacaf1a37cfa2ea9a319f30352eb026622cce365024f82b360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ece3feea68d01b37cbc4a8fcbe6b700e0faecc0709437de85cfa2836b973b8c060d340c666d0144bff35a372eef0718b5e21931e1acfaa80b00274c9e5d55a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef797e7dabba7dd97f08c054f8dbce5a1efcabf1d2148e53a98d396202fa0036291400d77de4c718dab2ec719e04ea1460c05e700341673885d888a7e5ba7b23"} build_url:https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010612 build_url: 'https://queue.taskcluster.net/v1/task/TxAWwqggTYi-RWuF_fdlGw/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/fbf066dc008374d1091fb607ac999326e654568b59ec7dc34c24fa26977cd51042adb7c6d9156ceacaf1a37cfa2ea9a319f30352eb026622cce365024f82b360", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4ece3feea68d01b37cbc4a8fcbe6b700e0faecc0709437de85cfa2836b973b8c060d340c666d0144bff35a372eef0718b5e21931e1acfaa80b00274c9e5d55a1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef797e7dabba7dd97f08c054f8dbce5a1efcabf1d2148e53a98d396202fa0036291400d77de4c718dab2ec719e04ea1460c05e700341673885d888a7e5ba7b23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:22.454550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:22.454862) ========= 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.QhvtmhAFNa/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.7a2W4mDRnB/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.005105 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 10:09:22.503490) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:09:22.503808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 10:09:22.504199) ========= ========= Total master_lag: 0.17 =========